1585452 - Intermittent org.mozilla.geckoview.test.NavigationDelegateTest#purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Reporter

Description

•

5 years ago

treeherder

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=269272135&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/afkSJRsEQfqhkkZsux37_g/runs/0/artifacts/public/logs/live_backing.log

[task 2019-10-01T18:26:36.561Z] 18:26:36 [task 2019-10-01T18:27:09.612Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.613Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.614Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 [task 2019-10-01T18:27:09.618Z] 18:27:09 INFO - TEST-START | org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=526
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
INFO - org.mozilla.geckoview.test | Error in purgeHistory(org.mozilla.geckoview.test.NavigationDelegateTest):
INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1524)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1466)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1419)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory(NavigationDelegateTest.kt:1377)
INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
INFO - org.mozilla.geckoview.test | at android.support.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
INFO - org.mozilla.geckoview.test | at android.support.test.internal.runner.junit4.statement.RunAfters.evaluate(RunAfters.java:61)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1257)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
INFO - org.mozilla.geckoview.test |
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner[task 2019-10-01T18:27:09.618Z] 18:27:09 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=purgeHistory
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.NavigationDelegateTest
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:58)
INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1524)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1466)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1419)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory(NavigationDelegateTest.kt:1377)
INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
INFO - org.mozilla.geckoview.test | at android.support.test.internal.runner.junit4.statement.RunBefores.evaluate(RunBefores.java:80)
INFO - org.mozilla.geckoview.test | at android.support.test.internal.runner.junit4.statement.RunAfters.evaluate(RunAfters.java:61)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1257)
INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
INFO - org.mozilla.geckoview.test |
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=247
INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory | status -2

Chris Peterson [:cpeterson]

Updated

•

5 years ago

Blocks: geckoview-junit-x86_64

status-firefox71: --- → affected

Comment hidden (Intermittent Failures Robot)

Pascal Chevrel:pascalc

Updated

•

4 years ago

status-firefox71: affected → wontfix

Comment hidden (Intermittent Failures Robot)

Oana Pop-Rus

Updated

•

4 years ago

Summary: Intermittent org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory | status -2 → Intermittent org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Emily Toop (:fluffyemily)

Updated

•

4 years ago

Rank: 20

Priority: P5 → P2

Comment hidden (Intermittent Failures Robot)

Emily Toop (:fluffyemily)

Updated

•

4 years ago

Whiteboard: [geckoview:m76]

Comment hidden (Intermittent Failures Robot)

Emily Toop (:fluffyemily)

Updated

•

4 years ago

Rank: 20

Priority: P2 → P5

Whiteboard: [geckoview:m76]

Comment hidden (Intermittent Failures Robot)

Emily Toop (:fluffyemily)

Updated

•

3 years ago

Whiteboard: [geckoview:m91?]

Comment hidden (Intermittent Failures Robot)

Emily Toop (:fluffyemily)

Updated

•

3 years ago

Whiteboard: [geckoview:m91?]

Comment hidden (Intermittent Failures Robot)

[ex-Mozilla] Agi Sferro | :agi

Updated

•

3 years ago

Priority: P5 → P3

Comment hidden (Intermittent Failures Robot)

[ex-Mozilla] Agi Sferro | :agi

Comment 81

•

3 years ago

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

Summary: Intermittent org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms → Intermittent org.mozilla.geckoview.test.NavigationDelegateTest#purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Comment hidden (Intermittent Failures Robot)

BMO Automation

Updated

•

2 years ago

Severity: normal → S3

Comment hidden (Intermittent Failures Robot)

Natalia Csoregi [:nataliaCs]

Comment 157

•

1 year ago

Update:

There have been 30 failures within the last 7 days:

5 failures on Android 7.0 x86-64 Lite WebRender opt
23 failures on Android 7.0 x86-64 WebRender debug/opt
2 failures on Android 7.0 x86-64 Lite WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=401731480&repo=mozilla-central&lineNumber=9729

[task 2023-01-08T22:16:38.056Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): Error
[task 2023-01-08T22:16:38.056Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-01-08T22:16:38.056Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2023-01-08T22:16:38.056Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-01-08T22:16:38.056Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1785)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1682)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1636)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.NavigationDelegateTest.purgeHistory(NavigationDelegateTest.kt:2804)
[task 2023-01-08T22:16:38.057Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2023-01-08T22:16:38.058Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1470)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(D8$$SyntheticClass)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-01-08T22:16:38.059Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.os.Looper.loop(Looper.java:154)
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.123 E/GeckoSessionTestRule( 3450): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.130 D/GeckoViewContent( 3450): handleEvent: DOMWindowClose
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.144 D/GeckoViewConsole( 3450): onEvent GeckoView:WebExtension:List null
[task 2023-01-08T22:16:38.060Z] 22:16:38     INFO -  01-08 22:16:37.155 E/GeckoSessionTestRule( 3450): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 purgeHistory(org.mozilla.geckoview.test.NavigationDelegateTest)
[task 2023-01-08T22:16:38.061Z] 22:16:38  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.NavigationDelegateTest#purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Chris, you are set as the owner of this component.
Can you help us assign the bug to someone?
Thank you.

Flags: needinfo?(cpeterson)

Whiteboard: [stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Chris Peterson [:cpeterson]

Updated

•

1 year ago

Rank: 250

Flags: needinfo?(cpeterson)

Priority: P3 → P2

Comment hidden (Intermittent Failures Robot)

Makoto Kato [:m_kato]

Assignee

Comment 160

•

1 year ago

I cannot reproduce this on local... Humm, I guess that this is a timing issue...

Comment hidden (Intermittent Failures Robot)

Marian-Vasile Laza

Comment 162

•

1 year ago

There have been 34 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

[task 2023-01-29T00:05:09.343Z] 00:05:09     INFO -  01-29 00:05:08.301 E/GeckoSessionTestRule( 3457): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2023-01-29T00:05:09.343Z] 00:05:09     INFO -  01-29 00:05:08.301 E/GeckoSessionTestRule( 3457): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-01-29T00:05:09.343Z] 00:05:09     INFO -  01-29 00:05:08.301 E/GeckoSessionTestRule( 3457): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2023-01-29T00:05:09.343Z] 00:05:09     INFO -  01-29 00:05:08.301 E/GeckoSessionTestRule( 3457): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2023-01-29T00:05:09.343Z] 00:05:09     INFO -  01-29 00:05:08.306 D/GeckoViewContent( 3457): handleEvent: DOMWindowClose
[task 2023-01-29T00:05:09.344Z] 00:05:09     INFO -  01-29 00:05:08.308 D/GeckoViewConsole( 3457): onEvent GeckoView:WebExtension:List null
[task 2023-01-29T00:05:09.344Z] 00:05:09     INFO -  01-29 00:05:08.314 E/GeckoSessionTestRule( 3457): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 purgeHistory(org.mozilla.geckoview.test.NavigationDelegateTest)
[task 2023-01-29T00:05:09.344Z] 00:05:09  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.NavigationDelegateTest#purgeHistory | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2023-01-29T00:05:09.344Z] 00:05:09     INFO -  TEST-INFO took 36524ms

Comment hidden (Intermittent Failures Robot)

Makoto Kato [:m_kato]

Assignee

Comment 164

•

1 year ago

No good information from adb logcat. Although I cannot reproduce this on my local, I get more information on CI.

[task 2023-02-01T08:31:34.975Z] 08:31:34     INFO -  02-01 08:31:02.759 D/GeckoSession( 3451): handleMessage GeckoView:StateUpdated
[task 2023-02-01T08:31:34.975Z] 08:31:34     INFO -  02-01 08:31:02.760 W/GeckoSession( 3451): No history entries found.
[task 2023-02-01T08:31:34.975Z] 08:31:34     INFO -  02-01 08:31:02.760 D/GeckoSession( 3451): data.update.hisotrychange={entries=[Lorg.mozilla.gecko.util.GeckoBundle;@ee54f0f, requestedIndex=0, fromIdx=-1, index=1}
[task 2023-02-01T08:31:34.976Z] 08:31:34     INFO -  02-01 08:31:02.760 D/GeckoSession( 3451): previousHistorySize=0 state.size()=1
[task 2023-02-01T08:31:34.976Z] 08:31:34     INFO -  02-01 08:31:02.760 D/GeckoSession( 3451): handleMessage GeckoView:StateUpdated uri=null
...
task 2023-02-01T08:31:34.982Z] 08:31:34     INFO -  02-01 08:31:02.934 D/GeckoViewContentDelegateParent( 3451): receiveMessage: DispatcherMessage
[task 2023-02-01T08:31:34.983Z] 08:31:34     INFO -  02-01 08:31:03.786 D/GeckoSession( 3451): handleMessage GeckoView:StateUpdated
[task 2023-02-01T08:31:34.983Z] 08:31:34     INFO -  02-01 08:31:03.787 D/GeckoSession( 3451): data.update.hisotrychange={entries=[Lorg.mozilla.gecko.util.GeckoBundle;@f87117a, requestedIndex=0, fromIdx=-1, index=1}
[task 2023-02-01T08:31:34.983Z] 08:31:34     INFO -  02-01 08:31:03.787 D/GeckoSession( 3451): previousHistorySize=1 state.size()=1

When historychange is called, but previousHistorySize isn't 2 when this occurs.

When loading hello2.html, mState.size() is also incorrect()...

And the following 2 logs has interesting error. But I am not sure that this is related.

[task 2023-02-01T08:31:34.979Z] 08:31:34     INFO -  02-01 08:31:02.839 I/Gecko   ( 3451): [Parent 3451, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/cookiebanners/nsCookieBannerService.cpp:786
[task 2023-02-01T08:31:34.979Z] 08:31:34     INFO -  02-01 08:31:02.839 I/Gecko   ( 3451): console.error: "Fail on getting domain pref:" ({})
[task 2023-02-01T08:31:34.979Z] 08:31:34     INFO -  02-01 08:31:02.840 E/GeckoConsole( 3451): [JavaScript Error: "Fail on getting domain pref: [Exception... "Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsICookieBannerService.getDomainPref]"  nsresult: "0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS)"  location: "JS frame :: resource://gre/actors/CookieBannerParent.jsm :: receiveMessage :: line 122"  data: no]"]

and

[task 2023-02-01T08:31:34.982Z] 08:31:34     INFO -  02-01 08:31:02.881 I/Gecko   ( 5785): [Child 5785, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-02-01T08:31:34.982Z] 08:31:34     INFO -  02-01 08:31:02.883 E/Web Content( 5785): [JavaScript Error: "InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment" {file: "resource://gre/modules/Messaging.sys.mjs" line: 27}]
[task 2023-02-01T08:31:34.982Z] 08:31:34     INFO -  02-01 08:31:02.934 D/GeckoViewContentDelegateParent( 3451): receiveMessage: DispatcherMessage

Makoto Kato [:m_kato]

Assignee

Comment 165

•

1 year ago

Ah, I guess that we try to load next page before history delegate is called according to log (https://firefoxci.taskcluster-artifacts.net/B6_N68TeS6SNNOYt4KydiA/0/public/logs/live_backing.log). I try to fix it.

Assignee: nobody → m_kato

Keywords: leave-open

Makoto Kato [:m_kato]

Assignee

Comment 166

•

1 year ago

Attached file Bug 1585452 - Check onHistoryStateChange call. r=#geckoview-reviewers — Details

When purgeHistory test is failure, we seem to try to load next page before
history delegate is called. So we should wait for onHistoryStateChange is
called.

Pulsebot

Comment 167

•

1 year ago

Pushed by m_kato@ga2.so-net.ne.jp:
https://hg.mozilla.org/integration/autoland/rev/da2c1fee7334
Check onHistoryStateChange call. r=geckoview-reviewers,ohall

Cristian Tuns

Comment 168

•

1 year ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/da2c1fee7334

Makoto Kato [:m_kato]

Assignee

Comment 169

•

1 year ago

After landing a fix, no Intermittent failure.

Status: NEW → RESOLVED

Closed: 1 year ago

status-firefox111: --- → fixed

Keywords: leave-open

Resolution: --- → FIXED

Target Milestone: --- → 111 Branch

Ryan VanderMeulen [:RyanVM]

Updated

•

1 year ago

status-firefox109: --- → wontfix

status-firefox110: --- → wontfix

Comment hidden (Intermittent Failures Robot)

Treeherder Bug Filer

Reporter

Updated

•

1 year ago