Closed Bug 1487881 Opened 7 years ago Closed 7 years ago

Intermittent testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java

Categories

(Firefox for Android Graveyard :: Reader View, defect, P5)

Product:
Firefox for Android Graveyard
Component:
Reader View
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: toros [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=196939602&repo=autoland https://queue.taskcluster.net/v1/task/MHY6Sm8-SQudRKsYgeIM_A/runs/0/artifacts/public/logs/live_backing.log [task 2018-08-31T18:57:44.286Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Robocop tests need the test device screen to be powered on. - [task 2018-08-31T18:57:44.286Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | url is not null - /robocop/robocop_javascript.html?slug=1535741619140&path=testReaderCacheMigration.js should not equal null [task 2018-08-31T18:57:44.287Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | url is not null - /robocop/robocop_javascript.html?slug=1535741619140&path=testReaderCacheMigration.js should not equal null [task 2018-08-31T18:57:44.287Z] 18:57:44 INFO - Buffered messages logged at 18:53:40 [task 2018-08-31T18:57:44.288Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | The toolbar is not in the editing state - [task 2018-08-31T18:57:44.288Z] 18:57:44 INFO - Buffered messages logged at 18:53:44 [task 2018-08-31T18:57:44.288Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Waiting for Toolbar to enter editing mode. - [task 2018-08-31T18:57:44.289Z] 18:57:44 INFO - Buffered messages logged at 18:53:46 [task 2018-08-31T18:57:44.289Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Waiting for UrlEditText to be input method target. - [task 2018-08-31T18:57:44.290Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | url is not null - http://mochi.test:8888/tests/robocop/robocop_javascript.html?slug=1535741619140&path=testReaderCacheMigration.js should not equal null [task 2018-08-31T18:57:44.290Z] 18:57:44 INFO - Buffered messages logged at 18:53:48 [task 2018-08-31T18:57:44.290Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | The toolbar is in the editing state - [task 2018-08-31T18:57:44.291Z] 18:57:44 INFO - Buffered messages logged at 18:53:49 [task 2018-08-31T18:57:44.291Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | The UrlEditText is the input method target - [task 2018-08-31T18:57:44.292Z] 18:57:44 INFO - Buffered messages logged at 18:54:07 [task 2018-08-31T18:57:44.292Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | The toolbar is in the editing state - [task 2018-08-31T18:57:44.293Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | initiatingAction is not null - org.mozilla.gecko.tests.components.ToolbarComponent$2@41ce8db0 should not equal null [task 2018-08-31T18:57:44.293Z] 18:57:44 INFO - Buffered messages logged at 18:54:08 [task 2018-08-31T18:57:44.293Z] 18:57:44 INFO - WaitHelper$ToolbarTitleTextChangeVerifier: stored title, "". [task 2018-08-31T18:57:44.294Z] 18:57:44 INFO - Buffered messages logged at 18:54:17 [task 2018-08-31T18:57:44.294Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Given message occurred for registered event: {type=null, tabID=0, title=Nightly Home} - Content:DOMTitleChanged should equal Content:DOMTitleChanged [task 2018-08-31T18:57:44.295Z] 18:57:44 INFO - Buffered messages logged at 18:54:18 [task 2018-08-31T18:57:44.295Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Given message occurred for registered event: {metadata=null, type=null, tabID=0, errorType=} - Content:DOMContentLoaded should equal Content:DOMContentLoaded [task 2018-08-31T18:57:44.296Z] 18:57:44 INFO - Buffered messages logged at 18:54:34 [task 2018-08-31T18:57:44.296Z] 18:57:44 INFO - WaitHelper$ToolbarTitleTextChangeVerifier: timed out. [task 2018-08-31T18:57:44.296Z] 18:57:44 INFO - Buffered messages logged at 18:54:36 [task 2018-08-31T18:57:44.297Z] 18:57:44 INFO - TEST-PASS | testReaderCacheMigration | Waiting for Toolbar to exit editing mode. - [task 2018-08-31T18:57:44.297Z] 18:57:44 INFO - Buffered messages finished [task 2018-08-31T18:57:44.297Z] 18:57:44 WARNING - TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java [task 2018-08-31T18:57:44.298Z] 18:57:44 INFO - 0 ERROR Exception caught during test! - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java [task 2018-08-31T18:57:44.299Z] 18:57:44 INFO - at junit.framework.Assert.fail(Assert.java:50) [task 2018-08-31T18:57:44.299Z] 18:57:44 INFO - at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:124) [task 2018-08-31T18:57:44.299Z] 18:57:44 INFO - at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:145) [task 2018-08-31T18:57:44.300Z] 18:57:44 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:113) [task 2018-08-31T18:57:44.300Z] 18:57:44 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:97) [task 2018-08-31T18:57:44.301Z] 18:57:44 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForBundle(FennecNativeActions.java:171) [task 2018-08-31T18:57:44.301Z] 18:57:44 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.processPendingMessage(JavascriptBridge.java:186) [task 2018-08-31T18:57:44.302Z] 18:57:44 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.ensureJavaBridgeLoaded(JavascriptBridge.java:222) [task 2018-08-31T18:57:44.302Z] 18:57:44 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.sendMessage(JavascriptBridge.java:227) [task 2018-08-31T18:57:44.302Z] 18:57:44 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.syncCall(JavascriptBridge.java:138) [task 2018-08-31T18:57:44.303Z] 18:57:44 INFO - at org.mozilla.gecko.tests.testReaderCacheMigration.checkPathMatches(testReaderCacheMigration.java:46) [task 2018-08-31T18:57:44.303Z] 18:57:44 INFO - at org.mozilla.gecko.tests.testReaderCacheMigration.testReaderCacheMigration(testReaderCacheMigration.java:59) [task 2018-08-31T18:57:44.304Z] 18:57:44 INFO - at java.lang.reflect.Method.invokeNative(Native Method) [task 2018-08-31T18:57:44.304Z] 18:57:44 INFO - at java.lang.reflect.Method.invoke(Method.java:525) [task 2018-08-31T18:57:44.305Z] 18:57:44 INFO - at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214) [task 2018-08-31T18:57:44.305Z] 18:57:44 INFO - at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199) [task 2018-08-31T18:57:44.305Z] 18:57:44 INFO - at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192) [task 2018-08-31T18:57:44.306Z] 18:57:44 INFO - at org.mozilla.gecko.tests.BaseRobocopTest.runTest(BaseRobocopTest.java:186) [task 2018-08-31T18:57:44.306Z] 18:57:44 INFO - at junit.framework.TestCase.runBare(TestCase.java:134) [task 2018-08-31T18:57:44.307Z] 18:57:44 INFO - at junit.framework.TestResult$1.protect(TestResult.java:115) [task 2018-08-31T18:57:44.307Z] 18:57:44 INFO - at junit.framework.TestResult.runProtected(TestResult.java:133) [task 2018-08-31T18:57:44.307Z] 18:57:44 INFO - at junit.framework.TestResult.run(TestResult.java:118) [task 2018-08-31T18:57:44.308Z] 18:57:44 INFO - at junit.framework.TestCase.run(TestCase.java:124) [task 2018-08-31T18:57:44.308Z] 18:57:44 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:191) [task 2018-08-31T18:57:44.309Z] 18:57:44 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:176) [task 2018-08-31T18:57:44.309Z] 18:57:44 INFO - at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:554) [task 2018-08-31T18:57:44.309Z] 18:57:44 INFO - at org.mozilla.gecko.FennecInstrumentationTestRunner.onStart(FennecInstrumentationTestRunner.java:66) [task 2018-08-31T18:57:44.310Z] 18:57:44 INFO - at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1701) [task 2018-08-31T18:57:44.310Z] 18:57:44 WARNING - TEST-UNEXPECTED-FAIL | testReaderCacheMigration | Exception caught - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java [task 2018-08-31T18:57:44.311Z] 18:57:44 INFO - GECKO | EventExpecter: no longer listening for Robocop:Java [task 2018-08-31T18:57:44.311Z] 18:57:44 INFO - TEST-OK | testReaderCacheMigration | took 249185ms [task 2018-08-31T18:57:44.312Z] 18:57:44 INFO - TEST-START | Shutdown [task 2018-08-31T18:57:44.312Z] 18:57:44 INFO - Passed: 14 [task 2018-08-31T18:57:44.312Z] 18:57:44 INFO - Failed: 2 [task 2018-08-31T18:57:44.313Z] 18:57:44 INFO - Todo: 0 [task 2018-08-31T18:57:44.313Z] 18:57:44 INFO - SimpleTest FINISHED
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=227769929&repo=autoland&lineNumber=1710

[task 2019-02-11T23:25:56.791Z] 23:25:56 INFO - TEST-PASS | testReaderCacheMigration | Given message occurred for registered event: {metadata=null, type=null, tabID=0, errorType=} - Content:DOMContentLoaded should equal Content:DOMContentLoaded
[task 2019-02-11T23:25:56.791Z] 23:25:56 INFO - Buffered messages logged at 23:22:49
[task 2019-02-11T23:25:56.791Z] 23:25:56 INFO - WaitHelper$ToolbarTitleTextChangeVerifier: timed out.
[task 2019-02-11T23:25:56.792Z] 23:25:56 INFO - Buffered messages logged at 23:22:51
[task 2019-02-11T23:25:56.792Z] 23:25:56 INFO - TEST-PASS | testReaderCacheMigration | Waiting for Toolbar to exit editing mode. -
[task 2019-02-11T23:25:56.793Z] 23:25:56 INFO - Buffered messages finished
[task 2019-02-11T23:25:56.793Z] 23:25:56 WARNING - TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2019-02-11T23:25:56.794Z] 23:25:56 INFO - 0 ERROR Exception caught during test! - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2019-02-11T23:25:56.794Z] 23:25:56 INFO - at junit.framework.Assert.fail(Assert.java:50)
[task 2019-02-11T23:25:56.795Z] 23:25:56 INFO - at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:124)
[task 2019-02-11T23:25:56.795Z] 23:25:56 INFO - at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:145)
[task 2019-02-11T23:25:56.799Z] 23:25:56 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:113)
[task 2019-02-11T23:25:56.800Z] 23:25:56 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:97)
[task 2019-02-11T23:25:56.800Z] 23:25:56 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForBundle(FennecNativeActions.java:171)
[task 2019-02-11T23:25:56.801Z] 23:25:56 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.processPendingMessage(JavascriptBridge.java:186)
[task 2019-02-11T23:25:56.801Z] 23:25:56 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.ensureJavaBridgeLoaded(JavascriptBridge.java:222)
[task 2019-02-11T23:25:56.802Z] 23:25:56 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.sendMessage(JavascriptBridge.java:227)
[task 2019-02-11T23:25:56.802Z] 23:25:56 INFO - at org.mozilla.gecko.tests.helpers.JavascriptBridge.syncCall(JavascriptBridge.java:138)
[task 2019-02-11T23:25:56.802Z] 23:25:56 INFO - at org.mozilla.gecko.tests.testReaderCacheMigration.checkPathMatches(testReaderCacheMigration.java:46)
[task 2019-02-11T23:25:56.803Z] 23:25:56 INFO - at org.mozilla.gecko.tests.testReaderCacheMigration.testReaderCacheMigration(testReaderCacheMigration.java:59)
[task 2019-02-11T23:25:56.803Z] 23:25:56 INFO - at java.lang.reflect.Method.invokeNative(Native Method)
[task 2019-02-11T23:25:56.804Z] 23:25:56 INFO - at java.lang.reflect.Method.invoke(Method.java:525)
[task 2019-02-11T23:25:56.804Z] 23:25:56 INFO - at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214)
[task 2019-02-11T23:25:56.805Z] 23:25:56 INFO - at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199)
[task 2019-02-11T23:25:56.805Z] 23:25:56 INFO - at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
[task 2019-02-11T23:25:56.806Z] 23:25:56 INFO - at org.mozilla.gecko.tests.BaseRobocopTest.runTest(BaseRobocopTest.java:188)
[task 2019-02-11T23:25:56.806Z] 23:25:56 INFO - at junit.framework.TestCase.runBare(TestCase.java:134)
[task 2019-02-11T23:25:56.806Z] 23:25:56 INFO - at junit.framework.TestResult$1.protect(TestResult.java:115)
[task 2019-02-11T23:25:56.807Z] 23:25:56 INFO - at junit.framework.TestResult.runProtected(TestResult.java:133)
[task 2019-02-11T23:25:56.807Z] 23:25:56 INFO - at junit.framework.TestResult.run(TestResult.java:118)
[task 2019-02-11T23:25:56.808Z] 23:25:56 INFO - at junit.framework.TestCase.run(TestCase.java:124)
[task 2019-02-11T23:25:56.808Z] 23:25:56 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:191)
[task 2019-02-11T23:25:56.808Z] 23:25:56 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:176)
[task 2019-02-11T23:25:56.809Z] 23:25:56 INFO - at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:554)
[task 2019-02-11T23:25:56.809Z] 23:25:56 INFO - at org.mozilla.gecko.FennecInstrumentationTestRunner.onStart(FennecInstrumentationTestRunner.java:66)
[task 2019-02-11T23:25:56.810Z] 23:25:56 INFO - at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1701)
[task 2019-02-11T23:25:56.810Z] 23:25:56 WARNING - TEST-UNEXPECTED-FAIL | testReaderCacheMigration | Exception caught - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderCacheMigration | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2019-02-11T23:25:56.811Z] 23:25:56 INFO - GECKO | EventExpecter: no longer listening for Robocop:Java
[task 2019-02-11T23:25:56.811Z] 23:25:56 INFO - TEST-OK | testReaderCacheMigration | took 247958ms

Product: Firefox for Android → Firefox for Android Graveyard
You need to log in before you can comment on or make changes to this bug.