Closed Bug 957185 Opened 11 years ago Closed 9 years ago

Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave,testHomeProvider,testDeviceSearchEngine | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready

Categories

(Firefox for Android Graveyard :: Testing, defect)

Product:
Firefox for Android Graveyard
Component:
Testing
Platform:
x86
macOS
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: gbrown, Assigned: gbrown)

References

Details

(Keywords: intermittent-failure)

https://tbpl.mozilla.org/php/getParsedLog.php?id=32621246&tree=Cedar&full=1 https://tbpl.mozilla.org/php/getParsedLog.php?id=32611079&tree=Cedar&full=1 https://tbpl.mozilla.org/php/getParsedLog.php?id=32610988&tree=Cedar&full=1 18:34:58 INFO - 2 INFO TEST-UNEXPECTED-FAIL | testPromptGridInput | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready 18:34:58 INFO - Exception caught during test! 18:34:58 INFO - junit.framework.AssertionFailedError: 2 INFO TEST-UNEXPECTED-FAIL | testPromptGridInput | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready 18:34:58 INFO - at junit.framework.Assert.fail(Assert.java:50) 18:34:58 INFO - at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:110) 18:34:58 INFO - at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:139) 18:34:58 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:103) 18:34:58 INFO - at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:87) 18:34:58 INFO - at org.mozilla.gecko.tests.BaseTest.blockForGeckoReady(BaseTest.java:85) 18:34:58 INFO - at org.mozilla.gecko.tests.testPromptGridInput.testPromptGridInput(testPromptGridInput.java:24) 18:34:58 INFO - at java.lang.reflect.Method.invokeNative(Native Method) 18:34:58 INFO - at java.lang.reflect.Method.invoke(Method.java:511) 18:34:58 INFO - at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214) 18:34:58 INFO - at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199) 18:34:58 INFO - at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192) 18:34:58 INFO - at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:150) 18:34:58 INFO - at junit.framework.TestCase.runBare(TestCase.java:134) 18:34:58 INFO - at junit.framework.TestResult$1.protect(TestResult.java:115) 18:34:58 INFO - at junit.framework.TestResult.runProtected(TestResult.java:133) 18:34:58 INFO - at junit.framework.TestResult.run(TestResult.java:118) 18:34:58 INFO - at junit.framework.TestCase.run(TestCase.java:124) 18:34:58 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:190) 18:34:58 INFO - at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:175) 18:34:58 INFO - at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:555) 18:34:58 INFO - at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1661) 18:34:58 INFO - 3 INFO TEST-UNEXPECTED-FAIL | testPromptGridInput | Exception caught - junit.framework.AssertionFailedError: 2 INFO TEST-UNEXPECTED-FAIL | testPromptGridInput | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready 18:34:58 INFO - 4 INFO TEST-END | testPromptGridInput | finished in 101067ms 18:34:58 INFO - 5 INFO TEST-START | Shutdown 18:34:58 INFO - 6 INFO Passed: 0 18:34:58 WARNING - 7 INFO Failed: 2
Last bit from logcat: 18:34:58 INFO - 01-07 02:12:29.992 W/GeckoGLController( 1994): GLController::serverSurfaceChanged(1024, 687) 18:34:58 INFO - 01-07 02:12:30.092 W/GeckoGLController( 1994): GLController::updateCompositor with mCompositorCreated=false 18:34:58 INFO - 01-07 02:12:30.092 W/GeckoGLController( 1994): done GLController::updateCompositor 18:34:58 INFO - 01-07 02:12:30.102 E/GeckoLibLoad( 1994): Load sqlite start 18:34:58 INFO - 01-07 02:12:30.102 E/GeckoLinker( 1994): /data/app/org.mozilla.fennec-1.apk!/assets/libnss3.so: Warning: unhandled flags #8 not handled 18:34:58 INFO - 01-07 02:12:30.112 E/GeckoLibLoad( 1994): Load sqlite done 18:34:58 INFO - 01-07 02:12:30.112 D/Robocop ( 1994): waiting for Gecko:Ready
Keywords: intermittent-failure
Attempt to reproduce with logging: https://tbpl.mozilla.org/?tree=Cedar&showall=1&rev=91cedff239c9
Summary: Android x86 intermittent robocop failure | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready → TEST-UNEXPECTED-FAIL | testPromptGridInput | testHomeBanner | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready
Summary: TEST-UNEXPECTED-FAIL | testPromptGridInput | testHomeBanner | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready → Intermittent testPromptGridInput,testHomeBanner | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready
This is the most frequent remaining Android x86 robocop failure. Tests disabled: https://hg.mozilla.org/integration/mozilla-inbound/rev/a26c945e9a5c
Whiteboard: [test disabled][leave open]
Summary: Intermittent testPromptGridInput,testHomeBanner | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready → Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready
Using bug 936226 instead.
Blocks: 936226
No longer blocks: 891959
https://tbpl.mozilla.org/php/getParsedLog.php?id=36017797&tree=Fx-Team
Summary: Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready → Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave,testHomeProvider | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready
Most of the Panda testPromptGridInput failures here show something like: 17:37:55 INFO - 03-12 17:34:50.390 E/GeckoLibLoad( 2156): Load sqlite start 17:37:55 INFO - 03-12 17:34:50.398 E/GeckoLibLoad( 2156): Load nss start 17:37:55 INFO - 03-12 17:34:50.398 F/libc ( 2156): Fatal signal 8 (SIGFPE) at 0x0000086c (code=-6) 17:37:55 INFO - 03-12 17:34:50.406 E/GeckoLinker( 2156): /data/app/org.mozilla.fennec-1.apk!/assets/libnss3.so: Warning: unhandled flags #8 not handled 17:37:55 INFO - 03-12 17:34:50.406 E/GeckoLinker( 2156): /data/app/org.mozilla.fennec-1.apk!/assets/libnss3.so: Warning: relocation to NULL @0x0018fc24 17:37:55 INFO - 03-12 17:34:50.414 E/GeckoLinker( 2156): /data/app/org.mozilla.fennec-1.apk!/assets/libnss3.so: Warning: relocation to NULL @0x0018ffdc for symbol "__cxa_begin_cleanup" 17:37:55 INFO - 03-12 17:34:50.414 E/GeckoLinker( 2156): /data/app/org.mozilla.fennec-1.apk!/assets/libnss3.so: Warning: relocation to NULL @0x0018ffe0 for symbol "__cxa_type_match" 17:37:55 INFO - 03-12 17:34:50.429 E/GeckoLibLoad( 2156): Load sqlite done 17:37:55 INFO - 03-12 17:34:50.429 D/Robocop ( 2156): waiting for Gecko:Ready 17:37:55 INFO - 03-12 17:34:50.492 I/GeckoDistribution( 2156): Getting file from distribution. 17:37:55 INFO - 03-12 17:34:50.578 D/GeckoToolbar( 2156): onTabChanged: MENU_UPDATED 17:37:55 INFO - 03-12 17:34:50.578 D/GeckoBrowserApp( 2156): BrowserApp.onTabChanged: 0: MENU_UPDATED 17:37:55 INFO - 03-12 17:34:50.585 W/GeckoLogger( 2156): fennec :: AnnounceBrSvc :: Current time 1394645690588 is older than build date 1394669616000. Ignoring until clock is corrected. 17:37:55 INFO - 03-12 17:34:50.593 E/GeckoJNI( 2156): missing NSS_Initialize 17:37:55 INFO - 03-12 17:34:50.593 E/GeckoLibLoad( 2156): Throw 17:37:55 INFO - 03-12 17:34:50.593 E/GeckoLibLoad( 2156): Load nss done 17:37:55 INFO - 03-12 17:34:50.593 W/dalvikvm( 2156): threadid=15: thread exiting with uncaught exception (group=0x40b671f8) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): >>> REPORTING UNCAUGHT EXCEPTION FROM THREAD 94 ("Gecko") 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): java.lang.Exception: Error loading nss libraries 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): at org.mozilla.gecko.mozglue.GeckoLoader.loadNSSLibsNative(Native Method) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): at org.mozilla.gecko.mozglue.GeckoLoader.loadNSSLibs(GeckoLoader.java:243) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): at org.mozilla.gecko.GeckoThread.initGeckoEnvironment(GeckoThread.java:117) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): at org.mozilla.gecko.GeckoThread.run(GeckoThread.java:169) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): Main thread stack: 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.GLES20Canvas.nDrawDisplayList(Native Method) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.GLES20Canvas.drawDisplayList(GLES20Canvas.java:363) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.HardwareRenderer$GlRenderer.draw(HardwareRenderer.java:847) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.ViewRootImpl.draw(ViewRootImpl.java:1910) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.ViewRootImpl.performTraversals(ViewRootImpl.java:1634) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.view.ViewRootImpl.handleMessage(ViewRootImpl.java:2442) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.os.Handler.dispatchMessage(Handler.java:99) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.os.Looper.loop(Looper.java:137) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): android.app.ActivityThread.main(ActivityThread.java:4424) 17:37:55 INFO - 03-12 17:34:50.617 E/GeckoAppShell( 2156): java.lang.reflect.Method.invokeNative(Native Method) 17:37:55 INFO - 03-12 17:34:50.625 E/GeckoAppShell( 2156): java.lang.reflect.Method.invoke(Method.java:511) 17:37:55 INFO - 03-12 17:34:50.625 E/GeckoAppShell( 2156): com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:784) 17:37:55 INFO - 03-12 17:34:50.625 E/GeckoAppShell( 2156): com.android.internal.os.ZygoteInit.main(ZygoteInit.java:551) 17:37:55 INFO - 03-12 17:34:50.625 E/GeckoAppShell( 2156): dalvik.system.NativeStart.main(Native Method)
Depends on: 959254
I remain interested in resolving all of the Gecko:Ready timeouts, but I am really just waiting to see what can be done in bug 959254.
Assignee: gbrown → nobody
https://tbpl.mozilla.org/php/getParsedLog.php?id=38270460&tree=Fx-Team
Summary: Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave,testHomeProvider | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready → Intermittent testPromptGridInput,testHomeBanner,testLoad,testSessionOOMSave,testHomeProvider,testDeviceSearchEngine | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready
Big spike in testHomeProvider fails in the last day.
Flags: needinfo?(mark.finkle)
The testHomeProvider spike correlates nicely with bug 1094649, so I'm going to move them over to that.
Flags: needinfo?(mark.finkle)
No longer blocks: 936226
The only test disabled here was on x86 robocop; that job is no longer run and the skip-if was removed from robocop.ini long ago.
Assignee: nobody → gbrown
Whiteboard: [test disabled][leave open]
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
Product: Firefox for Android → Firefox for Android Graveyard
You need to log in before you can comment on or make changes to this bug.