Closed Bug 1631206 Opened 6 years ago Closed 6 years ago

Perma TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.NavigationDelegateTest.extensionProcessSwitching | java.lang.AssertionError: URL should match Gecko 77 merges to release on 2020-05-04

Categories

(GeckoView :: Extensions, defect)

Unspecified
All
defect

Tracking

(firefox-esr68 unaffected, firefox75 unaffected, firefox76 unaffected, firefox77 verified)

VERIFIED FIXED
mozilla77
Tracking Status
firefox-esr68 --- unaffected
firefox75 --- unaffected
firefox76 --- unaffected
firefox77 --- verified

People

(Reporter: aiakab, Assigned: droeh)

References

(Regression)

Details

(Keywords: regression)

Attachments

(1 file)

[Tracking Requested - why for this release]:

Central as beta simulation:https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&revision=4eb4598e89ab84983f964708f4a3cc5887f3abb2&selectedJob=298232249

Log:https://treeherder.mozilla.org/logviewer.html#?job_id=298232249&repo=try

Failure log https://treeherder.mozilla.org/logviewer.html#?job_id=298232249&repo=try

Part of that log:

[task 2020-04-18T11:34:18.328Z] 11:34:18 INFO - TEST-START | org.mozilla.geckoview.test.NavigationDelegateTest.extensionProcessSwitching
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=674
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | Error in extensionProcessSwitching(org.mozilla.geckoview.test.NavigationDelegateTest):
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | java.lang.AssertionError: URL should match
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | Expected: "moz-extension://67405b8a-3a20-4b43-b16f-84c31b0c5873/page.html"
[task 2020-04-18T11:34:19.830Z] 11:34:19 INFO - org.mozilla.geckoview.test | but: was "http://localhost:4245/assets/www/hello.html"
[task 2020-04-18T11:34:19.831Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:801)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:81)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.processSwitchingTest(NavigationDelegateTest.kt:1468)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.extensionProcessSwitching(NavigationDelegateTest.kt:1413)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1284)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test |
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=extensionProcessSwitching
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.NavigationDelegateTest
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=java.lang.AssertionError: URL should match
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | Expected: "moz-extension://67405b8a-3a20-4b43-b16f-84c31b0c5873/page.html"
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | but: was "http://localhost:4245/assets/www/hello.html"
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:801)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:81)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.processSwitchingTest(NavigationDelegateTest.kt:1468)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.extensionProcessSwitching(NavigationDelegateTest.kt:1413)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-04-18T11:34:19.837Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1284)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test |
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=328
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-04-18T11:34:19.838Z] 11:34:19 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.NavigationDelegateTest.extensionProcessSwitching | java.lang.AssertionError: URL should match
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - TEST-INFO took 1509ms
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=674
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-04-18T11:34:19.838Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=userGesture
[task 2020-04-18T11:34:19.839Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.NavigationDelegateTest
[task 2020-04-18T11:34:19.839Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=329
[task 2020-04-18T11:34:19.839Z] 11:34:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2020-04-18T11:34:19.839Z] 11:34:19 INFO - TEST-START | org.mozilla.geckoview.test.NavigationDelegateTest.userGestur

Could this failure be regressed from Bug 1631054? Agi can you please take a look?

Flags: needinfo?(agi)
Regressed by: 1631054

Thanks, will take a look today (keeping the ni so I remember)

This is a regression from Bug 1619798 (the test used to pass with non-remote extensions before that bug). I'm assuming we're gonna disable the test for now?

Flags: needinfo?(agi) → needinfo?(droeh)
Regressed by: 1619798
No longer regressed by: 1631054
Has Regression Range: --- → yes
Keywords: regression
Assignee: nobody → droeh
Status: NEW → ASSIGNED

Arthur, I think the patch I have up should work, but I'm not sure how to test this properly (using central as a beta simulation, as you say). Can you advise?

Flags: needinfo?(droeh) → needinfo?(aiakab)

Instructions are at https://wiki.mozilla.org/Sheriffing/How_To/Beta_simulations or you can let sheriffs pick it up in ~14h for the next simulation.

Flags: needinfo?(aiakab)

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #5)

Instructions are at https://wiki.mozilla.org/Sheriffing/How_To/Beta_simulations or you can let sheriffs pick it up in ~14h for the next simulation.

Thanks! I think I'm confident enough I'll let it get picked up in the next sim.

Pushed by droeh@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/cc90d0a37a4b Update NavigationDelegateTest.extensionProcessSwitching to check whether or not extension process is enabled. r=agi
Status: ASSIGNED → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla77
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: