Closed Bug 979621 Opened 6 years ago Closed 6 years ago

Android 2.3 testNewTab |junit.framework.AssertionFailedError: EditText is not found!

Categories

(Firefox for Android :: Testing, defect)

x86_64
Linux
defect
Not set

Tracking

()

RESOLVED FIXED
Firefox 31

People

(Reporter: gbrown, Assigned: gbrown)

References

Details

(Whiteboard: [test disabled on Android 2.3])

Attachments

(1 file, 1 obsolete file)

https://tbpl.mozilla.org/php/getParsedLog.php?id=34517350&tree=Ash&full=1#error9

15:44:34     INFO -  1 INFO TEST-START | testNewTab
15:44:34     INFO -  2 INFO TEST-PASS | testNewTab | Given message occurred for registered event: {"type":"Gecko:Ready"} - Gecko:Ready should equal Gecko:Ready
15:44:34     INFO -  EventExpecter: no longer listening for Gecko:Ready
15:44:34     INFO -  3 INFO TEST-PASS | testNewTab | Checking elements - all elements present
15:44:34     INFO -  4 INFO TEST-PASS | testNewTab | Initial number of tabs correct - 1 should equal 1
15:44:34     INFO -  5 INFO TEST-PASS | testNewTab | waiting for add tab view - add tab view available
15:44:34     INFO -  6 INFO TEST-PASS | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
15:44:34     INFO -  7 INFO TEST-PASS | testNewTab | URL typed properly - http://mochi.test:8888/tests/robocop/robocop_blank_01.html should equal http://mochi.test:8888/tests/robocop/robocop_blank_01.html
15:44:34     INFO -  8 INFO TEST-PASS | testNewTab | Given message occurred for registered event: {"errorType":"","bgColor":"transparent","type":"DOMContentLoaded","tabID":0} - DOMContentLoaded should equal DOMContentLoaded
15:44:34     INFO -  EventExpecter: no longer listening for DOMContentLoaded
15:44:34     INFO -  9 INFO TEST-PASS | testNewTab | Number of tabs increased - 2 should equal 2
15:44:34     INFO -  10 INFO TEST-PASS | testNewTab | waiting for add tab view - add tab view available
15:44:34     INFO -  Exception caught during test!
15:44:34     INFO -  junit.framework.AssertionFailedError: EditText is not found!
15:44:34     INFO -  	at junit.framework.Assert.fail(Assert.java:47)
15:44:34     INFO -  	at junit.framework.Assert.assertTrue(Assert.java:20)
15:44:34     INFO -  	at com.jayway.android.robotium.solo.Waiter.waitForAndGetView(Waiter.java:510)
15:44:34     INFO -  	at com.jayway.android.robotium.solo.Getter.getView(Getter.java:50)
15:44:34     INFO -  	at com.jayway.android.robotium.solo.Solo.getEditText(Solo.java:1835)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest$1.isSatisfied(BaseTest.java:195)
15:44:34     INFO -  	at com.jayway.android.robotium.solo.Waiter.waitForCondition(Waiter.java:370)
15:44:34     INFO -  	at com.jayway.android.robotium.solo.Solo.waitForCondition(Solo.java:426)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.waitForCondition(BaseTest.java:302)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.focusUrlBar(BaseTest.java:192)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.enterUrl(BaseTest.java:211)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.inputAndLoadUrl(BaseTest.java:242)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.addTab(BaseTest.java:568)
15:44:34     INFO -  	at org.mozilla.gecko.tests.testNewTab.addTab(testNewTab.java:8)
15:44:34     INFO -  	at org.mozilla.gecko.tests.testNewTab.testNewTab(testNewTab.java:44)
15:44:34     INFO -  	at java.lang.reflect.Method.invokeNative(Native Method)
15:44:34     INFO -  	at java.lang.reflect.Method.invoke(Method.java:507)
15:44:34     INFO -  	at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:204)
15:44:34     INFO -  	at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:194)
15:44:34     INFO -  	at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:186)
15:44:34     INFO -  	at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:150)
15:44:34     INFO -  	at junit.framework.TestCase.runBare(TestCase.java:127)
15:44:34     INFO -  	at junit.framework.TestResult$1.protect(TestResult.java:106)
15:44:34     INFO -  	at junit.framework.TestResult.runProtected(TestResult.java:124)
15:44:34     INFO -  	at junit.framework.TestResult.run(TestResult.java:109)
15:44:34     INFO -  	at junit.framework.TestCase.run(TestCase.java:118)
15:44:34     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:169)
15:44:34     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:154)
15:44:34     INFO -  	at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:529)
15:44:34     INFO -  	at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1448)
15:44:34     INFO -  11 INFO TEST-UNEXPECTED-FAIL | testNewTab | Exception caught - junit.framework.AssertionFailedError: EditText is not found!
15:44:34     INFO -  12 INFO TEST-END | testNewTab | finished in 94611ms
15:44:34     INFO -  13 INFO TEST-START | Shutdown
15:44:34     INFO -  14 INFO Passed: 9
15:44:34  WARNING -  15 INFO Failed: 1
Blocks: 967704
https://hg.mozilla.org/integration/mozilla-inbound/rev/024b872d19e7
Whiteboard: [leave open] [test disabled on Android 2.3]
Blocks: 979921
No longer blocks: 967704
Depends on: 915449
This is probably a dup of 915449, but I need to re-test before I enable this on 2.3 again, and it may take a few days to get around to that.
With the fix for bug 915449 applied, Android 2.3 tests proceed a little further, but then:

https://tbpl.mozilla.org/php/getParsedLog.php?id=36783302&tree=Ash&full=1#error0

21:49:07     INFO -  5 INFO TEST-PASS | testAddonManager | Page title is correct - Add-ons should equal Add-ons
21:49:07     INFO -  Exception caught during test!
21:49:07     INFO -  junit.framework.AssertionFailedError: Click can not be completed!
21:49:07     INFO -  	at junit.framework.Assert.fail(Assert.java:47)
21:49:07     INFO -  	at junit.framework.Assert.assertTrue(Assert.java:20)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Clicker.clickOnScreen(Clicker.java:99)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Clicker.clickOnScreen(Clicker.java:182)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Clicker.clickOnScreen(Clicker.java:156)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Solo.clickOnView(Solo.java:974)
21:49:07     INFO -  	at org.mozilla.gecko.tests.BaseTest$1.isSatisfied(BaseTest.java:202)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Waiter.waitForCondition(Waiter.java:370)
21:49:07     INFO -  	at com.jayway.android.robotium.solo.Solo.waitForCondition(Solo.java:426)
21:49:07     INFO -  	at org.mozilla.gecko.tests.BaseTest.waitForCondition(BaseTest.java:306)
21:49:07     INFO -  	at org.mozilla.gecko.tests.BaseTest.focusUrlBar(BaseTest.java:194)
21:49:07     INFO -  	at org.mozilla.gecko.tests.BaseTest.enterUrl(BaseTest.java:215)
21:49:07     INFO -  	at org.mozilla.gecko.tests.BaseTest.inputAndLoadUrl(BaseTest.java:246)
21:49:07     INFO -  	at org.mozilla.gecko.tests.PixelTest.loadAndGetPainted(PixelTest.java:10)
21:49:07     INFO -  	at org.mozilla.gecko.tests.PixelTest.loadAndPaint(PixelTest.java:23)
21:49:07     INFO -  	at org.mozilla.gecko.tests.testAddonManager.testAddonManager(testAddonManager.java:49)
21:49:07     INFO -  	at java.lang.reflect.Method.invokeNative(Native Method)
Attachment #8398052 - Flags: review?(michael.l.comella)
Comment on attachment 8398052 [details] [diff] [review]
make focusUrlBar a little more robust - check view state before clicking

Review of attachment 8398052 [details] [diff] [review]:
-----------------------------------------------------------------

I'm not sure I understand how this fixes the issue, though I don't understand the problems in the underlying Robotium/Android source.

I found where the assertion is thrown from [1], and it appears that `Instrumentation.sendPointerSync` is throwing a `SecurityException` at least ten times (assuming the other lines are harmless). However, this an undocumented exception [2]. Do you know why this is thrown? Without that, I'm not sure what the motivation for guarding the click action is.

[1]: https://github.com/RobotiumTech/robotium/blob/robotium-4.3.1/robotium-solo/src/main/java/com/jayway/android/robotium/solo/Clicker.java#L99
[2]: https://developer.android.com/reference/android/app/Instrumentation.html#sendPointerSync%28android.view.MotionEvent%29
I do not know why that SecurityException is thrown. The success of the patch suggests that in some condition, that view is not visible and/or not clickable...and that we don't need to click it, at least not at that moment. A temporary condition that does not respond well to robotium's attempts to click? That's as much of an explanation as I can offer.
I meant to mention earlier that testing on Ash appears to fully resolve the frequent failure shown in comment 4.
After speaking on IRC, gbrown and I have speculated that the SecurityException is thrown because the View we're trying to click is not visible and thus has coordinates of 0,0. 0,0 is then clicked on the screen, which is likely the system bar, and the following error output appears:

21:49:07     INFO -  03-26 21:32:27.080 W/InputDispatcher(   64): Permission denied: injecting event from pid 478 uid 10017 to window with input channel 40644030 StatusBar (server) owned by uid 1000
21:49:07     INFO -  03-26 21:32:27.081 W/WindowManager(   64): Input event injection permission denied.

This is because we don't have permissions to touch the tap the system bar. The patch in comment 5 works because it doesn't click under the conditions above.

However, we're now wondering if the click is necessary at all, so gbrown is going to put a build on try without the associated clicking code.
Status: NEW → ASSIGNED
Attachment #8398052 - Flags: review?(michael.l.comella)
This alternative solution - removing the clickOnView call entirely - passes try and gives a clean run for Android 2.3.
Attachment #8398052 - Attachment is obsolete: true
Attachment #8398308 - Flags: review?(michael.l.comella)
Comment on attachment 8398308 [details] [diff] [review]
remove clickOnView entirely

Review of attachment 8398308 [details] [diff] [review]:
-----------------------------------------------------------------

r+ w/ nits.

::: mobile/android/base/tests/BaseTest.java
@@ +196,5 @@
>              public boolean isSatisfied() {
>                  EditText urlEditText = (EditText) mSolo.getView(R.id.url_edit_text);
>                  if (urlEditText.isInputMethodTarget()) {
>                      return true;
>                  } else {

nit: Just remove the else clause:

`if (urlEditText.isInputMethodTarget()) {
  return true;
 }

 return false;`
Attachment #8398308 - Flags: review?(michael.l.comella) → review+
There is still an intermittent failure:

https://tbpl.mozilla.org/php/getParsedLog.php?id=36902290&tree=Ash&full=1#error0

14:49:54     INFO -  4 INFO TEST-PASS | testNewTab | Initial number of tabs correct - 1 should equal 1
14:49:54     INFO -  5 INFO TEST-PASS | testNewTab | waiting for add tab view - add tab view available
14:49:54     INFO -  waitForCondition timeout after 10000 ms.
14:49:54     INFO -  6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  Exception caught during test!
14:49:54     INFO -  junit.framework.AssertionFailedError: 6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  	at junit.framework.Assert.fail(Assert.java:47)
14:49:54     INFO -  	at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:110)
14:49:54     INFO -  	at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:139)
14:49:54     INFO -  	at org.mozilla.gecko.tests.BaseTest.focusUrlBar(BaseTest.java:206)
14:49:54     INFO -  	at org.mozilla.gecko.tests.BaseTest.enterUrl(BaseTest.java:212)
14:49:54     INFO -  	at org.mozilla.gecko.tests.BaseTest.inputAndLoadUrl(BaseTest.java:243)
14:49:54     INFO -  	at org.mozilla.gecko.tests.BaseTest.addTab(BaseTest.java:567)
14:49:54     INFO -  	at org.mozilla.gecko.tests.testNewTab.addTab(testNewTab.java:8)
14:49:54     INFO -  	at org.mozilla.gecko.tests.testNewTab.testNewTab(testNewTab.java:39)
14:49:54     INFO -  	at java.lang.reflect.Method.invokeNative(Native Method)
14:49:54     INFO -  	at java.lang.reflect.Method.invoke(Method.java:507)
14:49:54     INFO -  	at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:204)
14:49:54     INFO -  	at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:194)
14:49:54     INFO -  	at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:186)
14:49:54     INFO -  	at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:152)
14:49:54     INFO -  	at junit.framework.TestCase.runBare(TestCase.java:127)
14:49:54     INFO -  	at junit.framework.TestResult$1.protect(TestResult.java:106)
14:49:54     INFO -  	at junit.framework.TestResult.runProtected(TestResult.java:124)
14:49:54     INFO -  	at junit.framework.TestResult.run(TestResult.java:109)
14:49:54     INFO -  	at junit.framework.TestCase.run(TestCase.java:118)
14:49:54     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:169)
14:49:54     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:154)
14:49:54     INFO -  	at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:529)
14:49:54     INFO -  	at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1448)
14:49:54     INFO -  7 INFO TEST-UNEXPECTED-FAIL | testNewTab | Exception caught - junit.framework.AssertionFailedError: 6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  8 INFO TEST-END | testNewTab | finished in 46117ms
14:49:54     INFO -  9 INFO TEST-START | Shutdown
14:49:54     INFO -  10 INFO Passed: 4
14:49:54  WARNING -  11 INFO Failed: 2
14:49:54  WARNING -  One or more unittests failed.
14:49:54     INFO -  12 INFO Todo: 0
14:49:54     INFO -  13 INFO SimpleTest FINISHED
14:49:54     INFO -  INFO | automation.py | Application ran for: 0:00:53.845693


14:49:54     INFO -  03-28 14:48:09.457 I/Robocop ( 1687): 5 INFO TEST-PASS | testNewTab | waiting for add tab view - add tab view available
14:49:54     INFO -  03-28 14:48:10.256 W/TwoWayView( 1687): Constructing LayoutParams with height FILL_PARENT does not make much sense as the view might change orientation. Falling back to WRAP_CONTENT
14:49:54     INFO -  03-28 14:48:10.567 D/GeckoToolbar( 1687): onTabChanged: THUMBNAIL
14:49:54     INFO -  03-28 14:48:10.580 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: THUMBNAIL
14:49:54     INFO -  03-28 14:48:11.236 D/GeckoTabs( 1687): handleMessage: Tab:Added
14:49:54     INFO -  03-28 14:48:11.408 D/GeckoTabs( 1687): Setting about: tab favicon inline.
14:49:54     INFO -  03-28 14:48:11.657 D/GeckoTabs( 1687): handleMessage: Content:StateChange
14:49:54     INFO -  03-28 14:48:11.797 D/GeckoToolbar( 1687): onTabChanged: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:11.797 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:11.813 D/GeckoToolbar( 1687): onTabChanged: ADDED
14:49:54     INFO -  03-28 14:48:11.817 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: ADDED
14:49:54     INFO -  03-28 14:48:11.833 D/GeckoToolbar( 1687): onTabChanged: SELECTED
14:49:54     INFO -  03-28 14:48:11.868 D/GeckoToolbarDisplayLayout( 1687): Ignoring favicon: new image is identical to previous one.
14:49:54     INFO -  03-28 14:48:11.877 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: SELECTED
14:49:54     INFO -  03-28 14:48:11.887 D/GeckoToolbar( 1687): onTabChanged: UNSELECTED
14:49:54     INFO -  03-28 14:48:11.896 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: UNSELECTED
14:49:54     INFO -  03-28 14:48:11.937 D/GeckoToolbar( 1687): onTabChanged: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:11.958 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:11.987 D/GeckoToolbar( 1687): onTabChanged: START
14:49:54     INFO -  03-28 14:48:11.987 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: START
14:49:54     INFO -  03-28 14:48:12.036 W/TwoWayView( 1687): Constructing LayoutParams with height FILL_PARENT does not make much sense as the view might change orientation. Falling back to WRAP_CONTENT
14:49:54     INFO -  03-28 14:48:12.667 I/ActivityManager(   64): Start proc org.mozilla.fennec.UpdateService for service org.mozilla.fennec/org.mozilla.gecko.updater.UpdateService: pid=1735 uid=10017 gids={3003, 1015, 1006}
14:49:54     INFO -  03-28 14:48:13.427 I/IdleService( 1687): Registering Idle observer callback
14:49:54     INFO -  03-28 14:48:13.457 I/IdleService( 1687): Register idle observer 5256cc80 for 180 seconds
14:49:54     INFO -  03-28 14:48:13.457 I/IdleService( 1687): Register: adjusting next switch from -1 to 180 seconds
14:49:54     INFO -  03-28 14:48:13.457 I/IdleService( 1687): next timeout 179974 msec from now
14:49:54     INFO -  03-28 14:48:13.467 I/IdleService( 1687): SetTimerExpiryIfBefore: next timeout 179972 msec from now
14:49:54     INFO -  03-28 14:48:13.477 I/IdleService( 1687): reset timer expiry to 179966 msec from now
14:49:54     INFO -  03-28 14:48:13.517 I/GeckoDisplayPort( 1687): Set strategy VelocityBiasStrategy mult=2.0, threshold=5.1200004, reverse=0.2, dangerBaseX=1.0, dangerBaseY=1.0, dangerIncrX=0.0, dangerIncrY=0.0
14:49:54     INFO -  03-28 14:48:14.007 D/szipinf ( 1735): Initializing inflate state
14:49:54     INFO -  03-28 14:48:14.056 I/dalvikvm( 1735): Could not find method android.view.ViewConfiguration.hasPermanentMenuKey, referenced from method org.mozilla.gecko.util.HardwareUtils.hasMenuButton
14:49:54     INFO -  03-28 14:48:14.086 W/dalvikvm( 1735): VFY: unable to resolve virtual method 5748: Landroid/view/ViewConfiguration;.hasPermanentMenuKey ()Z
14:49:54     INFO -  03-28 14:48:14.126 W/dalvikvm( 1735): VFY: unable to find class referenced in signature (Landroid/content/ClipboardManager;)
14:49:54     INFO -  03-28 14:48:14.126 W/dalvikvm( 1735): VFY: unable to find class referenced in signature (Landroid/content/ClipboardManager;)
14:49:54     INFO -  03-28 14:48:14.136 E/dalvikvm( 1735): Could not find class 'android.content.ClipboardManager', referenced from method org.mozilla.gecko.util.Clipboard.getClipboardManager$34116d45
14:49:54     INFO -  03-28 14:48:14.136 W/dalvikvm( 1735): VFY: unable to resolve check-cast 50 (Landroid/content/ClipboardManager;) in Lorg/mozilla/gecko/util/Clipboard;
14:49:54     INFO -  03-28 14:48:14.146 W/dalvikvm( 1735): VFY: unable to find class referenced in signature (Landroid/content/ClipboardManager;)
14:49:54     INFO -  03-28 14:48:14.146 I/dalvikvm( 1735): Could not find method android.content.ClipboardManager.hasPrimaryClip, referenced from method org.mozilla.gecko.util.Clipboard.getClipboardTextImpl
14:49:54     INFO -  03-28 14:48:14.146 W/dalvikvm( 1735): VFY: unable to resolve virtual method 211: Landroid/content/ClipboardManager;.hasPrimaryClip ()Z
14:49:54     INFO -  03-28 14:48:14.156 W/dalvikvm( 1735): VFY: unable to find class referenced in signature (Landroid/content/ClipboardManager;)
14:49:54     INFO -  03-28 14:48:14.176 I/dalvikvm( 1735): Could not find method android.content.ClipboardManager.hasPrimaryClip, referenced from method org.mozilla.gecko.util.Clipboard.hasText
14:49:54     INFO -  03-28 14:48:14.176 W/dalvikvm( 1735): VFY: unable to resolve virtual method 211: Landroid/content/ClipboardManager;.hasPrimaryClip ()Z
14:49:54     INFO -  03-28 14:48:14.507 I/dalvikvm( 1735): Could not find method android.app.ActivityManager.getLauncherLargeIconSize, referenced from method org.mozilla.gecko.GeckoAppShell.getPreferredIconSize
14:49:54     INFO -  03-28 14:48:14.517 W/dalvikvm( 1735): VFY: unable to resolve virtual method 78: Landroid/app/ActivityManager;.getLauncherLargeIconSize ()I
14:49:54     INFO -  03-28 14:48:14.756 E/dalvikvm( 1735): Could not find class 'android.view.TextureView', referenced from method org.mozilla.gecko.GeckoAppShell.initCamera
14:49:54     INFO -  03-28 14:48:14.756 W/dalvikvm( 1735): VFY: unable to resolve instanceof 865 (Landroid/view/TextureView;) in Lorg/mozilla/gecko/GeckoAppShell;
14:49:54     INFO -  03-28 14:48:14.787 D/GeckoTabs( 1687): handleMessage: Tab:Added
14:49:54     INFO -  03-28 14:48:14.817 E/dalvikvm( 1735): Could not find class 'android.graphics.SurfaceTexture', referenced from method org.mozilla.gecko.GeckoAppShell.registerSurfaceTextureFrameListener
14:49:54     INFO -  03-28 14:48:14.817 W/dalvikvm( 1735): VFY: unable to resolve check-cast 145 (Landroid/graphics/SurfaceTexture;) in Lorg/mozilla/gecko/GeckoAppShell;
14:49:54     INFO -  03-28 14:48:14.877 E/dalvikvm( 1735): Could not find class 'android.graphics.SurfaceTexture', referenced from method org.mozilla.gecko.GeckoAppShell.unregisterSurfaceTextureFrameListener
14:49:54     INFO -  03-28 14:48:14.887 W/dalvikvm( 1735): VFY: unable to resolve check-cast 145 (Landroid/graphics/SurfaceTexture;) in Lorg/mozilla/gecko/GeckoAppShell;
14:49:54     INFO -  03-28 14:48:14.947 D/GeckoTabs( 1687): handleMessage: Content:StateChange
14:49:54     INFO -  03-28 14:48:15.146 I/UpdateService( 1735): next update will be at: Fri Mar 28 18:25:26 PDT 2014
14:49:54     INFO -  03-28 14:48:15.407 D/GeckoToolbar( 1687): onTabChanged: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:15.420 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:15.429 D/GeckoToolbar( 1687): onTabChanged: START
14:49:54     INFO -  03-28 14:48:15.429 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: START
14:49:54     INFO -  03-28 14:48:17.727 D/GeckoTabs( 1687): handleMessage: SessionHistory:New
14:49:54     INFO -  03-28 14:48:17.867 D/GeckoTabs( 1687): handleMessage: Content:LocationChange
14:49:54     INFO -  03-28 14:48:17.877 D/GeckoToolbar( 1687): onTabChanged: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:17.877 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:17.877 D/GeckoToolbar( 1687): onTabChanged: LOCATION_CHANGE
14:49:54     INFO -  03-28 14:48:17.877 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: LOCATION_CHANGE
14:49:54     INFO -  03-28 14:48:17.897 D/GeckoTabs( 1687): handleMessage: Content:SecurityChange
14:49:54     INFO -  03-28 14:48:17.897 D/GeckoToolbar( 1687): onTabChanged: SECURITY_CHANGE
14:49:54     INFO -  03-28 14:48:17.897 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: SECURITY_CHANGE
14:49:54     INFO -  03-28 14:48:18.427 D/GeckoTabs( 1687): handleMessage: SessionHistory:New
14:49:54     INFO -  03-28 14:48:18.669 D/GeckoTabs( 1687): handleMessage: Content:LocationChange
14:49:54     INFO -  03-28 14:48:18.676 D/GeckoToolbar( 1687): onTabChanged: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:18.676 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: MENU_UPDATED
14:49:54     INFO -  03-28 14:48:18.708 D/GeckoToolbar( 1687): onTabChanged: LOCATION_CHANGE
14:49:54     INFO -  03-28 14:48:18.708 D/GeckoToolbarDisplayLayout( 1687): Ignoring favicon: new image is identical to previous one.
14:49:54     INFO -  03-28 14:48:18.708 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: LOCATION_CHANGE
14:49:54     INFO -  03-28 14:48:18.750 D/GeckoTabs( 1687): handleMessage: Content:SecurityChange
14:49:54     INFO -  03-28 14:48:18.750 D/GeckoToolbar( 1687): onTabChanged: SECURITY_CHANGE
14:49:54     INFO -  03-28 14:48:18.750 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: SECURITY_CHANGE
14:49:54     INFO -  03-28 14:48:19.621 D/GeckoTabs( 1687): handleMessage: DOMTitleChanged
14:49:54     INFO -  03-28 14:48:19.621 D/GeckoToolbar( 1687): onTabChanged: TITLE
14:49:54     INFO -  03-28 14:48:19.661 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: TITLE
14:49:54     INFO -  03-28 14:48:19.916 D/GeckoTabs( 1687): handleMessage: DOMContentLoaded
14:49:54     INFO -  03-28 14:48:20.156 D/GeckoToolbar( 1687): onTabChanged: LOADED
14:49:54     INFO -  03-28 14:48:20.156 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: LOADED
14:49:54     INFO -  03-28 14:48:20.896 D/GeckoTabs( 1687): handleMessage: Content:PageShow
14:49:54     INFO -  03-28 14:48:20.907 D/GeckoToolbar( 1687): onTabChanged: PAGE_SHOW
14:49:54     INFO -  03-28 14:48:20.907 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: PAGE_SHOW
14:49:54     INFO -  03-28 14:48:20.957 D/GeckoTabs( 1687): handleMessage: Tab:ViewportMetadata
14:49:54     INFO -  03-28 14:48:20.967 D/GeckoToolbar( 1687): onTabChanged: VIEWPORT_CHANGE
14:49:54     INFO -  03-28 14:48:20.967 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: VIEWPORT_CHANGE
14:49:54     INFO -  03-28 14:48:21.060 D/GeckoTabs( 1687): handleMessage: Content:StateChange
14:49:54     INFO -  03-28 14:48:21.076 D/GeckoToolbar( 1687): onTabChanged: STOP
14:49:54     INFO -  03-28 14:48:21.076 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: STOP
14:49:54     INFO -  03-28 14:48:21.407 D/GeckoTabs( 1687): handleMessage: DOMTitleChanged
14:49:54     INFO -  03-28 14:48:21.428 D/GeckoToolbar( 1687): onTabChanged: TITLE
14:49:54     INFO -  03-28 14:48:21.428 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: TITLE
14:49:54     INFO -  03-28 14:48:21.576 D/GeckoToolbar( 1687): onTabChanged: THUMBNAIL
14:49:54     INFO -  03-28 14:48:21.576 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 1: THUMBNAIL
14:49:54     INFO -  03-28 14:48:21.956 D/GeckoTabs( 1687): handleMessage: DOMContentLoaded
14:49:54     INFO -  03-28 14:48:21.967 D/GeckoToolbar( 1687): onTabChanged: LOADED
14:49:54     INFO -  03-28 14:48:21.967 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: LOADED
14:49:54     INFO -  03-28 14:48:22.176 I/Robocop ( 1687): waitForCondition timeout after 10000 ms.
14:49:54     INFO -  03-28 14:48:22.206 I/Robocop ( 1687): 6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): Exception caught during test!
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): junit.framework.AssertionFailedError: 6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.Assert.fail(Assert.java:47)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:110)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:139)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.BaseTest.focusUrlBar(BaseTest.java:206)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.BaseTest.enterUrl(BaseTest.java:212)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.BaseTest.inputAndLoadUrl(BaseTest.java:243)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.BaseTest.addTab(BaseTest.java:567)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.testNewTab.addTab(testNewTab.java:8)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.testNewTab.testNewTab(testNewTab.java:39)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at java.lang.reflect.Method.invokeNative(Native Method)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at java.lang.reflect.Method.invoke(Method.java:507)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:204)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:194)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:186)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:152)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.TestCase.runBare(TestCase.java:127)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.TestResult$1.protect(TestResult.java:106)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.TestResult.runProtected(TestResult.java:124)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.TestResult.run(TestResult.java:109)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at junit.framework.TestCase.run(TestCase.java:118)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:169)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:154)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:529)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 	at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1448)
14:49:54     INFO -  03-28 14:48:22.676 I/Robocop ( 1687): 7 INFO TEST-UNEXPECTED-FAIL | testNewTab | Exception caught - junit.framework.AssertionFailedError: 6 INFO TEST-UNEXPECTED-FAIL | testNewTab | waiting for urlbar text to gain focus - urlbar text gained focus
14:49:54     INFO -  03-28 14:48:22.700 I/Robocop ( 1687): 8 INFO TEST-END | testNewTab | finished in 46117ms
14:49:54     INFO -  03-28 14:48:22.716 I/Robocop ( 1687): 9 INFO TEST-START | Shutdown
14:49:54     INFO -  03-28 14:48:22.729 I/Robocop ( 1687): 10 INFO Passed: 4
14:49:54     INFO -  03-28 14:48:22.729 I/Robocop ( 1687): 11 INFO Failed: 2
14:49:54     INFO -  03-28 14:48:22.729 I/Robocop ( 1687): 12 INFO Todo: 0
14:49:54     INFO -  03-28 14:48:22.729 I/Robocop ( 1687): 13 INFO SimpleTest FINISHED
14:49:54     INFO -  03-28 14:48:22.816 D/GeckoTabs( 1687): handleMessage: Tab:ViewportMetadata
14:49:54     INFO -  03-28 14:48:23.226 D/GeckoTabs( 1687): handleMessage: Content:PageShow
14:49:54     INFO -  03-28 14:48:23.267 D/GeckoTabs( 1687): handleMessage: Content:StateChange
14:49:54     INFO -  03-28 14:48:23.340 D/GeckoToolbar( 1687): onTabChanged: VIEWPORT_CHANGE
14:49:54     INFO -  03-28 14:48:23.340 D/GeckoBrowserApp( 1687): BrowserApp.onTabChanged: 0: VIEWPORT_CHANGE


Notice that there is still a lot of browser activity when the wait times out.
Status: ASSIGNED → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
Duplicate of bug: 995696
I believe comment 15 was unrelated to this bug, but rather related to bug 995696, so I'm going to mark fixed instead.
Resolution: DUPLICATE → FIXED
Whiteboard: [leave open] [test disabled on Android 2.3] → [test disabled on Android 2.3]
Target Milestone: --- → Firefox 31
You need to log in before you can comment on or make changes to this bug.