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

Assignee

Description

•

10 years ago

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

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

Blocks: 967704

Geoff Brown [:gbrown]

Assignee

Comment 1

•

10 years ago

https://hg.mozilla.org/integration/mozilla-inbound/rev/024b872d19e7

Whiteboard: [leave open] [test disabled on Android 2.3]

Carsten Book [:Tomcat]

Comment 2

•

10 years ago

https://hg.mozilla.org/mozilla-central/rev/024b872d19e7

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

Blocks: 979921

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

No longer blocks: 967704

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

Depends on: 915449

Geoff Brown [:gbrown]

Assignee

Comment 3

•

10 years ago

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.

Geoff Brown [:gbrown]

Assignee

Comment 4

•

10 years ago

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)

Geoff Brown [:gbrown]

Assignee

Comment 5

•

10 years ago

Attached patch make focusUrlBar a little more robust - check view state before clicking (obsolete) — Details — Splinter Review

Attachment #8398052 - Flags: review?(michael.l.comella)

Michael Comella (:mcomella) [NI reported issues only: ex-Mozilla]

Comment 6

•

10 years ago

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

Geoff Brown [:gbrown]

Assignee

Comment 7

•

10 years ago

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.

Geoff Brown [:gbrown]

Assignee

Comment 8

•

10 years ago

I meant to mention earlier that testing on Ash appears to fully resolve the frequent failure shown in comment 4.

Michael Comella (:mcomella) [NI reported issues only: ex-Mozilla]

Comment 9

•

10 years ago

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

Geoff Brown [:gbrown]

Assignee

Comment 10

•

10 years ago

Patch from comment 5: https://tbpl.mozilla.org/?tree=Try&rev=48178671fdee

Alternate solution -- remove click entirely: https://tbpl.mozilla.org/?tree=Try&rev=f934a4f4005f

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

Attachment #8398052 - Flags: review?(michael.l.comella)

Geoff Brown [:gbrown]

Assignee

Comment 11

•

10 years ago

Attached patch remove clickOnView entirely — Details — Splinter Review

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)

Michael Comella (:mcomella) [NI reported issues only: ex-Mozilla]

Comment 12

•

10 years ago

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+

Geoff Brown [:gbrown]

Assignee

Comment 13

•

10 years ago

w/ nit: https://hg.mozilla.org/integration/mozilla-inbound/rev/37377f1c2852

Phil Ringnalda (:philor)

Comment 14

•

10 years ago

https://hg.mozilla.org/mozilla-central/rev/37377f1c2852

Geoff Brown [:gbrown]

Assignee

Comment 15

•

10 years ago

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.

Michael Comella (:mcomella) [NI reported issues only: ex-Mozilla]

Comment 16

•

10 years ago

Is comment 15 this bug 995696? If so, can we close this?

Geoff Brown [:gbrown]

Assignee

Updated

•

10 years ago

Status: ASSIGNED → RESOLVED

Closed: 10 years ago

Resolution: --- → DUPLICATE

Michael Comella (:mcomella) [NI reported issues only: ex-Mozilla]

Comment 18

•

10 years ago

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]

Ryan VanderMeulen [:RyanVM]

Updated

•

10 years ago

Target Milestone: --- → Firefox 31

BMO Automation

Updated

•

3 years ago

Product: Firefox for Android → Firefox for Android Graveyard

make focusUrlBar a little more robust - check view state before clicking 10 years ago Geoff Brown [:gbrown] 1.24 KB, patch		Details \| Diff \| Splinter Review
remove clickOnView entirely 10 years ago Geoff Brown [:gbrown] 1.05 KB, patch	mcomella : review+	Details \| Diff \| Splinter Review