1717506 - Intermittent org.mozilla.geckoview.test.AutofillDelegateTest#autofillNavigation | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED FIXED

(GeckoView :: General, defect, P1)

Description

[Treeherder Bug Filer]

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=343412126&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YPe_YEdqRUqwkFednTaSOg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-06-21T20:07:29.522Z] 20:07:29     INFO -  06-21 21:07:00.968 I/Gecko   ( 3145): nsWindow 0x710ee1a59800 destructor
[task 2021-06-21T20:07:29.522Z] 20:07:29     INFO -  06-21 21:07:01.009 D/ServiceAllocator( 3145): org.mozilla.gecko.process.GeckoChildProcessServices$tab37 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2021-06-21T20:07:29.522Z] 20:07:29     INFO -  06-21 21:07:07.224 E/GeckoConsole( 3145): [JavaScript Error: "NS_ERROR_FILE_NOT_FOUND: " {file: "resource://gre/modules/SessionStoreFunctions.jsm" line: 106}]
[task 2021-06-21T20:07:29.522Z] 20:07:29     INFO -  06-21 21:07:07.224 E/GeckoConsole( 3145): SSF_updateSessionStoreForWindow@resource://gre/modules/SessionStoreFunctions.jsm:106:5
[task 2021-06-21T20:07:29.522Z] 20:07:29     INFO -  06-21 21:07:07.224 E/GeckoConsole( 3145): UpdateSessionStoreForStorage@resource://gre/modules/SessionStoreFunctions.jsm:49:35
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): Error
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:52)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:155)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1903)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:185)
[task 2021-06-21T20:07:29.523Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.AutofillDelegateTest.autofillNavigation(AutofillDelegateTest.kt:308)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1317)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$cmP_QJg-X16GIlt8KIQd32egmfU.run(lambda)
[task 2021-06-21T20:07:29.524Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.os.Looper.loop(Looper.java:154)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.285 E/GeckoSessionTestRule( 3145): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.289 D/GeckoViewContent( 3145): handleEvent: DOMWindowClose
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.292 D/GeckoViewConsole( 3145): onEvent GeckoView:WebExtension:List null
[task 2021-06-21T20:07:29.525Z] 20:07:29     INFO -  06-21 21:07:28.301 I/Gecko   ( 3145): nsWindow[0x710ee1a61c00]::Show 0
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  06-21 21:07:28.301 E/GeckoSessionTestRule( 3145): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 autofillNavigation(org.mozilla.geckoview.test.AutofillDelegateTest)
[task 2021-06-21T20:07:29.526Z] 20:07:29  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.AutofillDelegateTest.autofillNavigation | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  TEST-INFO took 31300ms
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=815
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=autofill
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.AutofillDelegateTest
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=54
[task 2021-06-21T20:07:29.526Z] 20:07:29     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Comment 1

[Marian-Vasile Laza]

This looks to have appeared after bug 1715300 landed: https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=Android%2C7.0%2Cx86-64%2Cdebug%2Ctest-android-em-7.0-x86_64%2Fdebug-geckoview-junit-fis-e10s%2Cgv-junit-fis&tochange=997f00815e6bc28806b75448c8829f0259d2cb28&fromchange=2b1d4ef5f3eafef5a1a347e3d25f888ab4ea198a&selectedTaskRun=Fu8iy542SNyVCnNYPi8--w.0

Peter, could you please take a look?

Comment 2

[Intermittent Failures Robot]

20 failures in 3115 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 19
• mozilla-central: 1

Platform and build breakdown:

• android-em-7-0-x86_64: 9
  • debug-isolated-process: 5
  • debug: 4
• android-em-7-0-x86_64-qr: 8
  • debug: 8
• android-em-7-0-x86_64-shippable: 2
  • opt: 2
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-06-21&endday=2021-06-27&tree=all

Comment 3

[Intermittent Failures Robot]

11 failures in 723 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 5

Platform and build breakdown:

• android-em-7-0-x86_64: 7
  • debug: 6
  • debug-isolated-process: 1
• android-em-7-0-x86_64-qr: 4
  • debug: 3
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-06-28&endday=2021-07-04&tree=all

Comment 4

[Intermittent Failures Robot]

19 failures in 2387 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• mozilla-central: 3

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 14
  • debug: 14
• android-em-7-0-x86_64: 5
  • debug: 3
  • opt: 1
  • debug-isolated-process: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-07-05&endday=2021-07-11&tree=all

Comment 5

[Intermittent Failures Robot]

27 failures in 2224 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #50 most frequent failure this week.

Repository breakdown:

• mozilla-central: 6
• autoland: 21

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 7
  • debug: 3
  • opt: 4
• android-em-7-0-x86_64: 17
  • debug: 7
  • debug-isolated-process: 9
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-07-12&endday=2021-07-18&tree=all

Comment 6

[Intermittent Failures Robot]

25 failures in 2940 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 8

Platform and build breakdown:

• android-em-7-0-x86_64: 9
  • debug: 2
  • debug-isolated-process: 7
• android-em-7-0-x86_64-qr: 9
  • debug: 5
  • debug-isolated-process: 3
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 3
  • opt: 3
• android-em-7-0-x86_64-lite: 1
  • debug: 1
• android-em-7-0-x86_64-lite-qr: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-07-19&endday=2021-07-25&tree=all

Comment 7

[[ex-Mozilla] Agi Sferro | :agi]

6c6f5da9-bf33-4082-a19d-7d99e8083d97 Mass rename intermittents due to Bug 1723034.

Comment 8

[Intermittent Failures Robot]

15 failures in 622 pushes (0.024 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 10
• mozilla-central: 5

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 9
  • debug: 4
  • debug-isolated-process: 4
  • opt: 1
• android-em-7-0-x86_64-lite-qr: 5
  • debug: 5
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-07-30&endday=2021-07-30&tree=all

Comment 9

[Intermittent Failures Robot]

52 failures in 3131 pushes (0.017 failures/push) were associated with this bug in the last 7 days.

This is the #20 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 40
• mozilla-central: 12

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 34
  • debug: 14
  • debug-isolated-process: 16
  • opt: 4
• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-shippable-lite-qr: 4
  • opt: 4
• android-em-7-0-x86_64-lite-qr: 12
  • debug: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-07-26&endday=2021-08-01&tree=all

Comment 10

[Iulian Moraru]

Update:
There have been 54 failures within the last 7 days:
• 1 failures on android-em-7-0-x86_64-lite-qr opt
• 21 failures on android-em-7-0-x86_64-lite-qr debug
• 2 failures on Android 7.0 x86-64 WebRender opt
• 10 failures on Android 7.0 x86-64 WebRender debug
• 12 failures on Android 7.0 x86-64 WebRender debug-isolated-process
• 3 failures on android-em-7-0-x86_64-shippable-lite-qr opt
• 5 failures on Android 7.0 x86-64 Shippable WebRender opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=347587796&repo=autoland&lineNumber=3091

[task 2021-08-05T23:02:53.166Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=796
[task 2021-08-05T23:02:53.166Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-08-05T23:02:53.167Z] 23:02:53     INFO -  org.mozilla.geckoview.test | Error in autofillNavigation(org.mozilla.geckoview.test.AutofillDelegateTest):
[task 2021-08-05T23:02:53.167Z] 23:02:53     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-08-05T23:02:53.167Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:50)
[task 2021-08-05T23:02:53.168Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-08-05T23:02:53.168Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-08-05T23:02:53.168Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:153)
[task 2021-08-05T23:02:53.168Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1901)
[task 2021-08-05T23:02:53.169Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:188)
[task 2021-08-05T23:02:53.169Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.AutofillDelegateTest.autofillNavigation(AutofillDelegateTest.kt:308)
[task 2021-08-05T23:02:53.169Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-08-05T23:02:53.169Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-08-05T23:02:53.170Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-08-05T23:02:53.170Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-08-05T23:02:53.170Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-08-05T23:02:53.170Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1315)
[task 2021-08-05T23:02:53.171Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$cmP_QJg-X16GIlt8KIQd32egmfU.run(lambda)
[task 2021-08-05T23:02:53.171Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-08-05T23:02:53.171Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-08-05T23:02:53.171Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-08-05T23:02:53.172Z] 23:02:53     INFO -  org.mozilla.geckoview.test |
[task 2021-08-05T23:02:53.173Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
<...>
[task 2021-08-05T23:02:53.287Z] 23:02:53     INFO -  08-05 23:02:22.341 D/GeckoViewProgress( 3476): receiveMessage: MozAfterPaint
[task 2021-08-05T23:02:53.287Z] 23:02:53     INFO -  08-05 23:02:23.982 I/Gecko   ( 3476): nsWindow 0x7c3e7fc65c00 destructor
[task 2021-08-05T23:02:53.287Z] 23:02:53     INFO -  08-05 23:02:23.982 I/Gecko   ( 3476): nsWindow 0x7c3e82022400 destructor
[task 2021-08-05T23:02:53.287Z] 23:02:53     INFO -  08-05 23:02:23.983 I/Gecko   ( 3476): nsWindow 0x7c3e7f9dd400 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:23.983 I/Gecko   ( 3476): nsWindow 0x7c3e7ef60800 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:23.983 I/Gecko   ( 3476): nsWindow 0x7c3e7f9d0c00 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:23.983 I/Gecko   ( 3476): nsWindow 0x7c3e7ef69800 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:24.259 I/Gecko   ( 3476): nsWindow 0x7c3e8237a400 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:24.259 I/Gecko   ( 3476): nsWindow 0x7c3e80be9800 destructor
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:25.052 D/ServiceAllocator( 3476): org.mozilla.gecko.process.GeckoChildProcessServices$tab27 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): Error
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:50)
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-08-05T23:02:53.288Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:153)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:1901)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:188)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.AutofillDelegateTest.autofillNavigation(AutofillDelegateTest.kt:308)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1315)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$cmP_QJg-X16GIlt8KIQd32egmfU.run(lambda)
[task 2021-08-05T23:02:53.289Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.os.Looper.loop(Looper.java:154)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.343 E/GeckoSessionTestRule( 3476): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.344 D/EGL_emulation( 3476): eglMakeCurrent: 0x7c3ea938d520: ver 3 0 (tinfo 0x7c3ea9394540)
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.346 D/GeckoViewContent( 3476): handleEvent: DOMWindowClose
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.347 D/GeckoViewConsole( 3476): onEvent GeckoView:WebExtension:List null
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.351 I/Gecko   ( 3476): nsWindow[0x7c3e7f9d2800]::Show 0
[task 2021-08-05T23:02:53.290Z] 23:02:53     INFO -  08-05 23:02:52.351 E/GeckoSessionTestRule( 3476): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 autofillNavigation(org.mozilla.geckoview.test.AutofillDelegateTest)
[task 2021-08-05T23:02:53.291Z] 23:02:53  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.AutofillDelegateTest#autofillNavigation | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  TEST-INFO took 31184ms
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=796
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=autofill
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.AutofillDelegateTest
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=57
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2021-08-05T23:02:53.291Z] 23:02:53     INFO -  TEST-START | org.mozilla.geckoview.test.AutofillDelegateTest#autofill

Comment 11

[Intermittent Failures Robot]

38 failures in 2829 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #38 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 27
• mozilla-central: 11

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 18
  • debug: 17
  • opt: 1
• android-em-7-0-x86_64-qr: 12
  • opt: 1
  • debug-isolated-process: 6
  • debug: 5
• android-em-7-0-x86_64-shippable-lite-qr: 3
  • opt: 3
• android-em-7-0-x86_64-shippable-qr: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-08-02&endday=2021-08-08&tree=all

Comment 12

[Intermittent Failures Robot]

25 failures in 3025 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 11
• autoland: 14

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 17
  • debug: 9
  • debug-isolated-process: 8
• android-em-7-0-x86_64-lite-qr: 7
  • debug: 7
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-08-09&endday=2021-08-15&tree=all

Comment 13

[Alexandru Michis [:malexandru]]

There have been 37 total failures in the last 7 days (recent failure log).
Affected platforms are:

• android-em-7-0-x86_64-lite-qr debug
• android-em-7-0-x86_64-qr debug
• android-em-7-0-x86_64-shippable-lite-qr

Comment 14

[Intermittent Failures Robot]

39 failures in 2899 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #23 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 27
• mozilla-central: 10
• try: 2

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 21
  • debug-isolated-process: 11
  • debug: 10
• android-em-7-0-x86_64-lite-qr: 15
  • debug: 15
• android-em-7-0-x86_64-shippable-lite-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-08-16&endday=2021-08-22&tree=all

Comment 15

[Intermittent Failures Robot]

25 failures in 2580 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• mozilla-central: 9

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 17
  • debug-isolated-process: 10
  • debug: 7
• android-em-7-0-x86_64-lite-qr: 5
  • debug: 5
• android-em-7-0-x86_64-shippable-lite-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-08-23&endday=2021-08-29&tree=all

Comment 16

[Intermittent Failures Robot]

39 failures in 3084 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #21 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 27
• mozilla-central: 11
• try: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 20
  • debug: 8
  • debug-isolated-process: 12
• android-em-7-0-x86_64-lite-qr: 17
  • debug: 16
  • opt: 1
• android-em-7-0-x86_64-shippable-lite-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-08-30&endday=2021-09-05&tree=all

Comment 17

[Intermittent Failures Robot]

17 failures in 629 pushes (0.027 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• mozilla-central: 2

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 8
  • debug: 8
• android-em-7-0-x86_64-qr: 8
  • debug: 6
  • debug-isolated-process: 2
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-08&endday=2021-09-08&tree=all

Comment 18

[Intermittent Failures Robot]

59 failures in 3266 pushes (0.018 failures/push) were associated with this bug in the last 7 days.

This is the #10 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 12
• autoland: 46
• try: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 26
  • debug-isolated-process: 15
  • debug: 9
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 32
  • debug: 31
  • opt: 1
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-06&endday=2021-09-12&tree=all

Comment 19

[Marian-Vasile Laza]

There have been 64 total failures in the last 7 days, recent failure log.
Affected platforms are:

• android-em-7-0-x86_64-lite-qr
• android-em-7-0-x86_64-qr
• android-em-7-0-x86_64-shippable-lite-qr
• android-em-7-0-x86_64-shippable-qr

Comment 20

[Intermittent Failures Robot]

62 failures in 3375 pushes (0.018 failures/push) were associated with this bug in the last 7 days.

This is the #12 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 49
• mozilla-central: 10
• try: 3

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 37
  • debug: 21
  • debug-isolated-process: 15
  • opt: 1
• android-em-7-0-x86_64-lite-qr: 23
  • debug: 23
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 21

[Intermittent Failures Robot]

18 failures in 616 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 10
• mozilla-central: 8

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 13
  • debug: 9
  • debug-isolated-process: 4
• android-em-7-0-x86_64-lite-qr: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-23&endday=2021-09-23&tree=all

Comment 22

[Cristian Tuns]

Attachment: Bug 1717506 Disabled AutofillDelegate on debug build r=#intermittent-reviewers

Comment 23

[Intermittent Failures Robot]

62 failures in 2873 pushes (0.022 failures/push) were associated with this bug in the last 7 days.

This is the #10 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 44
• mozilla-central: 18

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 48
  • opt: 1
  • debug: 31
  • debug-isolated-process: 16
• android-em-7-0-x86_64-lite-qr: 13
  • debug: 12
  • opt: 1
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-20&endday=2021-09-26&tree=all

Comment 24

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/4bfab129d922
Disabled AutofillDelegate on debug build r=intermittent-reviewers,jmaher DONTBUILD

Comment 25

[Cristian Tuns]

https://hg.mozilla.org/mozilla-central/rev/4bfab129d922

Comment 26

[[:owlish] 🦉 PST]

Assigning this to myself because I think I am already working on this (or what looks very similar to this). Cannot merge this work https://bugzilla.mozilla.org/show_bug.cgi?id=1703976 due to this intermittent

Comment 27

[Intermittent Failures Robot]

11 failures in 3376 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 2

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 3
  • debug: 1
  • opt: 2
• android-em-7-0-x86_64-qr: 7
  • debug-isolated-process: 3
  • debug: 4
• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-09-27&endday=2021-10-03&tree=all

Comment 28

[Intermittent Failures Robot]

8 failures in 3626 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 7

Platform and build breakdown:

• android-em-7-0-x86_64-shippable-lite-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 4
  • opt: 4
• android-em-7-0-x86_64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-10-04&endday=2021-10-10&tree=all

Comment 29

[Intermittent Failures Robot]

5 failures in 3315 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-10-11&endday=2021-10-17&tree=all

Comment 30

[Intermittent Failures Robot]

6 failures in 3186 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform and build breakdown:

• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-qr: 3
  • opt: 3
• android-em-7-0-x86_64-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-10-18&endday=2021-10-24&tree=all

Comment 31

[Intermittent Failures Robot]

3 failures in 2845 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• android-em-7-0-x86_64-shippable-lite-qr: 2
  • opt: 2
• android-em-7-0-x86_64-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-10-25&endday=2021-10-31&tree=all

Comment 32

[[ex-Mozilla] Agi Sferro | :agi]

Looking at the logs, it seems like GV is navigating to about:blank after it goes back to forms.html here: https://searchfox.org/mozilla-central/rev/4f9bbbe5487da6d1c3680488e016f7bb0cbaa128/mobile/android/geckoview/src/androidTest/java/org/mozilla/geckoview/test/AutofillDelegateTest.kt#328

11-02 03:17:05.544 D/GeckoViewNavigation( 3371): onLocationChange
11-02 03:17:05.545 D/GeckoSession( 3371): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/forms.html
11-02 03:17:05.546 D/GeckoViewProgress( 3371): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/forms.html, flags=0
11-02 03:17:05.547 D/GeckoViewProgress( 3371): ProgressTracker onStateChange: isTopLevel=false, flags=0x10c0010, status=NS_OK
11-02 03:17:05.548 D/GeckoViewProgress( 3371): StateTracker onStateChange: isTopLevel=false, flags=0x10c0010, status=NS_OK loadType=4
11-02 03:17:05.549 D/GeckoViewProgress( 3371): ProgressTracker onStateChange: isTopLevel=false, flags=0x10f0001, status=NS_OK
11-02 03:17:05.550 D/GeckoViewProgress( 3371): StateTracker onStateChange: isTopLevel=false, flags=0x10f0001, status=NS_OK loadType=4
11-02 03:17:05.551 D/GeckoViewNavigation( 3371): onLocationChange
11-02 03:17:05.553 D/GeckoSession( 3371): handleMessage GeckoView:LocationChange uri=about:blank
11-02 03:17:05.553 E/eglCodecCommon( 3371): glUtilsParamSize: unknow param 0x00008caa
11-02 03:17:05.555 D/GeckoViewProgress( 3371): SecurityTracker onLocationChange: location=about:blank, flags=0

that... makes no sense? Unless maybe the tab is crashing? there's nothing in the logs though.

Comment 33

[[ex-Mozilla] Agi Sferro | :agi]

Oh. I completely missed this code in forms.html:

       addEventListener("load", function(e) {
            if (window.parent === window) {
                document.getElementById("iframe").contentWindow.location.href = window.location.href;
            }
      }

https://searchfox.org/mozilla-central/rev/4f9bbbe5487da6d1c3680488e016f7bb0cbaa128/mobile/android/geckoview/src/androidTest/assets/www/forms.html#28-30

OK so that would make sense if somehow window.location.href is about:blank? and the navigation is a subframe navigation.

Comment 34

[[ex-Mozilla] Agi Sferro | :agi]

OK so I added more logs. It looks like this is caused by a mismatched port connect/disconnect call.

Navigating from forms.html to hello.html and pressing back we get

connect from forms.html
disconnect from forms.html
connect from hello.html
connect from forms.html
disconnect from hello.html

at the last disconnect call we destroy the port for forms.html (even though the disconnect call is for hello.html) because we don't expect calls to be out of order. Checking for the port object should fix this problem.

Comment 35

[[ex-Mozilla] Agi Sferro | :agi]

Attachment: Bug 1717506 - Handle out-of-order page{hide,show}.

With Fission BFCache, when navigating back the two pages might run in different
processes, even though they belong to the same domain.

This means that pagehide and pageshow might come out of order. For example,
navigating from a.html to b.html and back, we might get

pageshow b.html
pageshow a.html
pagehide b.html

notice we get the pageshow event for a.html before the pagehide event
for b.html.

Internally in our test framework, we use pagehide and pageshow to establish
a connection between a page and the Java layer through a WebExtension port.

Before this patch, we assumed tha all pageshow events will match a pagehide
event in order, and thus disconnect the port early for a.html when getting
the event for b.html in the above example.

There is also a separate problem where if a port was established for a given
web page and then a navigation was issues, we would hang waiting for a response
from the page that navigated away (and thus, not able to send messages
anymore).

To fix both of these issues, we keep track of all pending messages and discard
them properly when a Port is disconnected, and make sure that the port that's
disconnecting matches the one that's currently associated with the session (to
ensure that we are not disconnecting because of a out-of-order port event).

Comment 36

[[ex-Mozilla] Agi Sferro | :agi]

Attachment: Bug 1717506 - Re-enable autofillNavigation.

There is a potential intermittent where we try to execute javascript in the
page before the page navigates away, causing a hang. To ensure that that is not
possible we also wait for a PageStop event.

Comment 37

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/f86b50b2ab51
Handle out-of-order page{hide,show}. r=owlish
https://hg.mozilla.org/integration/autoland/rev/8cc25a79e24c
Re-enable autofillNavigation. r=owlish

Comment 38

[Cristina Cozmuta (:CrissCozmuta)]

https://hg.mozilla.org/mozilla-central/rev/f86b50b2ab51
https://hg.mozilla.org/mozilla-central/rev/8cc25a79e24c

Comment 39

[Intermittent Failures Robot]

2 failures in 3030 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1717506&startday=2021-11-01&endday=2021-11-07&tree=all

Comment 40

[[ex-Mozilla] Agi Sferro | :agi]

This should be fixed now.