Intermittent gv-junit-e10s-multi runjunit.py | The previous test failed because of an error in the test harness | org.mozilla.geckoview.test.rule#TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
Categories
(GeckoView Graveyard :: Sandboxing, defect, P2)
Tracking
(Not tracked)
People
(Reporter: bugzilla, Assigned: bugzilla)
References
Details
(Keywords: intermittent-failure, Whiteboard: [geckoview:m78][geckoview:m79][geckoview:m80][Comment 14][stockwell disable-recommended])
+++ This bug was initially created as a clone of Bug #1607878 +++
Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=284063816&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ccSYunWJRUKXT7KJzFnwRg/runs/0/artifacts/public/logs/live_backing.log
[task 2020-01-08T18:55:53.345Z] 18:55:53 INFO - TEST-START | org.mozilla.geckoview.test.ExtensionActionTest.setBadgeBackgroundColor[#pageAction]
[task 2020-01-08T18:56:23.392Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=622
[task 2020-01-08T18:56:23.392Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-01-08T18:56:23.392Z] 18:56:23 INFO - org.mozilla.geckoview.test | Error in setBadgeBackgroundColor#pageAction:
[task 2020-01-08T18:56:23.393Z] 18:56:23 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.initTest(GeckoSessionTestRule.java:1285)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1297)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | Caused by: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1142)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.prepareStatement(GeckoSessionTestRule.java:1092)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.initTest(GeckoSessionTestRule.java:1281)
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | ... 10 more
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test |
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=setBadgeBackgroundColor[#pageAction]
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ExtensionActionTest
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - SUITE-END | took 73s
[task 2020-01-08T18:56:23.396Z] 18:56:23 WARNING - TEST-UNEXPECTED-FAIL | runjunit.py | The previous test failed because of an error in the test harness | org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-01-08T18:56:23.396Z] 18:56:23 INFO - Stopping web server
[task 2020-01-08T18:56:23.397Z] 18:56:23 INFO - Stopping web socket server
[task 2020-01-08T18:56:23.417Z] 18:56:23 INFO - Stopping ssltunnel
[task 2020-01-08T18:56:26.139Z] 18:56:26 ERROR - Return code: 255
[task 2020-01-08T18:56:26.139Z] 18:56:26 ERROR - No tests run or test summary not found
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - TinderboxPrint: geckoview-junit<br/><em class="testfail">T-FAIL</em>
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - ##### geckoview-junit log ends
[task 2020-01-08T18:56:26.140Z] 18:56:26 WARNING - # TBPL WARNING #
[task 2020-01-08T18:56:26.140Z] 18:56:26 WARNING - setting return code to 1
[task 2020-01-08T18:56:26.140Z] 18:56:26 WARNING - The geckoview-junit suite: geckoview-junit ran with return status: WARNING
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - Running post-action listener: _package_coverage_data
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - Running post-action listener: _resource_record_post_action
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - Running post-action listener: process_java_coverage_data
[task 2020-01-08T18:56:26.140Z] 18:56:26 INFO - Running post-action listener: stop_device
Assignee | ||
Comment 1•5 years ago
|
||
I took a look at some logs yesterday. We clean up the session(s) from a previous test, and then, while starting up a new mainSession
for the next test, occasionally the content process hangs during startup.
The last thing I saw in the logcat was GeckoThread: zerdatime 399702 - runGecko
. At some point from there everything just hung.
Assignee | ||
Updated•5 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 4•4 years ago
|
||
This bug failed 91 times in the last 7 days. Occurs on android-em-7-0-x86_64 on OPT build types.
Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=302621207&repo=autoland&lineNumber=11270
Aaron: Any updates on this bug?
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 7•4 years ago
|
||
Let's see how this looks after bug 1625326 merges.
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•4 years ago
|
Updated•4 years ago
|
Comment 9•4 years ago
|
||
The failure rate has decreased, but there are still occurrences. For the last 7 days there are 24 total failures.
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•4 years ago
|
Comment 11•4 years ago
•
|
||
Aaron, this has reached the disable recommended queue, are there any updates?
Should we disable until there is a fix?
There are 30 total failures in the last 7 days on android-em-7-0-x86_64 opt
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=305277187&repo=autoland&lineNumber=11436
[task 2020-06-05T23:04:32.130Z] 23:04:32 INFO - TEST-START | org.mozilla.geckoview.test.StorageControllerTest.clearDataFlags
[task 2020-06-05T23:05:02.086Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=736
[task 2020-06-05T23:05:02.086Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-06-05T23:05:02.086Z] 23:05:02 INFO - org.mozilla.geckoview.test | Error in clearDataFlags(org.mozilla.geckoview.test.StorageControllerTest):
[task 2020-06-05T23:05:02.086Z] 23:05:02 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-06-05T23:05:02.087Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.initTest(GeckoSessionTestRule.java:1280)
[task 2020-06-05T23:05:02.087Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1292)
[task 2020-06-05T23:05:02.087Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-06-05T23:05:02.087Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-06-05T23:05:02.087Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-06-05T23:05:02.088Z] 23:05:02 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-06-05T23:05:02.089Z] 23:05:02 INFO - org.mozilla.geckoview.test | Caused by: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-06-05T23:05:02.089Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2020-06-05T23:05:02.089Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-06-05T23:05:02.089Z] 23:05:02 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.openSession(GeckoSessionTestRule.java:1145)
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.prepareStatement(GeckoSessionTestRule.java:1090)
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.initTest(GeckoSessionTestRule.java:1276)
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | ... 10 more
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test |
[task 2020-06-05T23:05:02.090Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-06-05T23:05:02.091Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=clearDataFlags
[task 2020-06-05T23:05:02.091Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.StorageControllerTest
[task 2020-06-05T23:05:02.091Z] 23:05:02 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-06-05T23:05:02.091Z] 23:05:02 INFO - SUITE-END | took 630s
[task 2020-06-05T23:05:02.091Z] 23:05:02 WARNING - TEST-UNEXPECTED-FAIL | runjunit.py | The previous test failed because of an error in the test harness | org.mozilla.geckoview.test.rule.TestHarnessException: org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2020-06-05T23:05:02.092Z] 23:05:02 INFO - Stopping web server
[task 2020-06-05T23:05:02.104Z] 23:05:02 INFO - Stopping web socket server
[task 2020-06-05T23:05:02.125Z] 23:05:02 INFO - Stopping ssltunnel
[task 2020-06-05T23:05:03.113Z] 23:05:03 ERROR - Return code: 255
[task 2020-06-05T23:05:03.113Z] 23:05:03 ERROR - No tests run or test summary not found
[task 2020-06-05T23:05:03.113Z] 23:05:03 INFO - TinderboxPrint: geckoview-junit<br/><em class="testfail">T-FAIL</em>
[task 2020-06-05T23:05:03.113Z] 23:05:03 INFO - ##### geckoview-junit log ends
[task 2020-06-05T23:05:03.114Z] 23:05:03 WARNING - # TBPL WARNING #
[task 2020-06-05T23:05:03.114Z] 23:05:03 WARNING - setting return code to 1
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 14•4 years ago
|
||
It became a permafailure with the push of bug 1641948: https://treeherder.mozilla.org/#/jobs?repo=autoland&revision=61fccfec51e98f51d9865bf1ff5808016a948a71
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 17•4 years ago
•
|
||
This bug failed 38 times in the last 7 days on: android-em-7-0-x86_64-shippable opt.
Aaron, could you take a look please?
Comment hidden (Intermittent Failures Robot) |
Updated•4 years ago
|
Comment hidden (Intermittent Failures Robot) |
Comment 20•4 years ago
|
||
In the last 7 days there 62 total failures on android-em-7-0-x86_64-shippable and android-em-7-0-x86_64 platforms, opt build type.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=307738036&repo=mozilla-central&lineNumber=11423
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 22•4 years ago
|
||
No failures since bug 1647470 landed. Resolving.
Assignee | ||
Updated•4 years ago
|
Comment 23•3 years ago
|
||
6c6f5da9-bf33-4082-a19d-7d99e8083d97 Mass rename intermittents due to Bug 1723034.
Comment 24•2 years ago
|
||
Moving some e10s bugs to the new GeckoView::Sandboxing component.
Updated•6 months ago
|
Description
•