1660357 - Intermittent org.mozilla.geckoview.test.PanZoomControllerTest#touchEventForResult | java.lang.AssertionError: Value should match

Status: RESOLVED FIXED

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-08-20T21:39:28.735Z] 21:39:28 INFO - TEST-START | org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult
[task 2020-08-20T21:39:28.949Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-08-20T21:39:28.949Z] 21:39:28 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult | java.lang.AssertionError: Value should match
[task 2020-08-20T21:39:28.949Z] 21:39:28 INFO - TEST-INFO took 208ms
[task 2020-08-20T21:39:28.950Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=756
[task 2020-08-20T21:39:28.950Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-08-20T21:39:28.950Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-08-20T21:39:28.950Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=scrollToVerticalOnZoomedContentSmooth
[task 2020-08-20T21:39:28.950Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PanZoomControllerTest
[task 2020-08-20T21:39:28.951Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=390
[task 2020-08-20T21:39:28.951Z] 21:39:28 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Comment 1

[Razvan Maries]

James, could you take a look?

Comment 2

[James Willcox (:snorp) ([e-mail]) (he/him)]

Geez. I thought I had the intermittents figured out. I'll work on it some more.

Comment 3

[Intermittent Failures Robot]

44 failures in 643 pushes (0.068 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-central: 3
• try: 24

Platform breakdown:

• android-em-7-0-x86_64: 15
• android-em-7-0-x86_64-shippable: 7
• linux1804-64-tsan: 1
• macosx1014-64-qr: 1
• macosx1014-64: 3
• linux1804-64: 11
• windows10-64-qr: 2
• windows10-64: 2
• linux1804-64-asan-qr: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1660357&startday=2020-08-21&endday=2020-08-21&tree=all

Comment 4

[Andreea Pavel [:apavel]]

There are 46 total failures in the last 7 days on trunk.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=313752756&repo=autoland&lineNumber=8303

[task 2020-08-22T22:44:47.386Z] 22:44:47 INFO - TEST-START | org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult
[task 2020-08-22T22:44:47.687Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=756
[task 2020-08-22T22:44:47.687Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-08-22T22:44:47.687Z] 22:44:47 INFO - org.mozilla.geckoview.test | Error in touchEventForResult(org.mozilla.geckoview.test.PanZoomControllerTest):
[task 2020-08-22T22:44:47.688Z] 22:44:47 INFO - org.mozilla.geckoview.test | java.lang.AssertionError: Value should match
[task 2020-08-22T22:44:47.688Z] 22:44:47 INFO - org.mozilla.geckoview.test | Expected: <1>
[task 2020-08-22T22:44:47.688Z] 22:44:47 INFO - org.mozilla.geckoview.test | but: was <0>
[task 2020-08-22T22:44:47.689Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2020-08-22T22:44:47.689Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2020-08-22T22:44:47.689Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2020-08-22T22:44:47.689Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2020-08-22T22:44:47.690Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2020-08-22T22:44:47.690Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:802)
[task 2020-08-22T22:44:47.690Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:88)
[task 2020-08-22T22:44:47.691Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult(PanZoomControllerTest.kt:279)
[task 2020-08-22T22:44:47.691Z] 22:44:47 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-08-22T22:44:47.691Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-08-22T22:44:47.691Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-08-22T22:44:47.691Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-08-22T22:44:47.692Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-08-22T22:44:47.692Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1302)
[task 2020-08-22T22:44:47.692Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-08-22T22:44:47.693Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-08-22T22:44:47.693Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-08-22T22:44:47.693Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-08-22T22:44:47.693Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-08-22T22:44:47.693Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-08-22T22:44:47.694Z] 22:44:47 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-08-22T22:44:47.694Z] 22:44:47 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-08-22T22:44:47.694Z] 22:44:47 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-08-22T22:44:47.694Z] 22:44:47 INFO - org.mozilla.geckoview.test |
[task 2020-08-22T22:44:47.694Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-08-22T22:44:47.695Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=touchEventForResult
[task 2020-08-22T22:44:47.695Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PanZoomControllerTest
[task 2020-08-22T22:44:47.695Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=java.lang.AssertionError: Value should match
[task 2020-08-22T22:44:47.695Z] 22:44:47 INFO - org.mozilla.geckoview.test | Expected: <1>
[task 2020-08-22T22:44:47.696Z] 22:44:47 INFO - org.mozilla.geckoview.test | but: was <0>
[task 2020-08-22T22:44:47.696Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2020-08-22T22:44:47.696Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2020-08-22T22:44:47.696Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2020-08-22T22:44:47.696Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2020-08-22T22:44:47.697Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2020-08-22T22:44:47.697Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:802)
[task 2020-08-22T22:44:47.697Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:88)
[task 2020-08-22T22:44:47.697Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult(PanZoomControllerTest.kt:279)
[task 2020-08-22T22:44:47.697Z] 22:44:47 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-08-22T22:44:47.698Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2020-08-22T22:44:47.698Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2020-08-22T22:44:47.698Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2020-08-22T22:44:47.698Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1302)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$sIbRNaZJgAu-QrUVWSGD8JbPSWM.run(lambda)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2020-08-22T22:44:47.699Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test |
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=389
[task 2020-08-22T22:44:47.700Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2020-08-22T22:44:47.700Z] 22:44:47 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.PanZoomControllerTest.touchEventForResult | java.lang.AssertionError: Value should match
[task 2020-08-22T22:44:47.701Z] 22:44:47 INFO - TEST-INFO took 305ms
[task 2020-08-22T22:44:47.701Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=756
[task 2020-08-22T22:44:47.701Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2020-08-22T22:44:47.701Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2020-08-22T22:44:47.702Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=scrollToVerticalOnZoomedContentSmooth
[task 2020-08-22T22:44:47.702Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PanZoomControllerTest
[task 2020-08-22T22:44:47.702Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=390
[task 2020-08-22T22:44:47.702Z] 22:44:47 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1

Comment 5

[Intermittent Failures Robot]

15 failures in 225 pushes (0.067 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-central: 2

Platform breakdown:

• android-em-7-0-x86_64: 10
• android-em-7-0-x86_64-shippable: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1660357&startday=2020-08-22&endday=2020-08-22&tree=all

Comment 6

[Intermittent Failures Robot]

83 failures in 4100 pushes (0.02 failures/push) were associated with this bug in the last 7 days.

This is the #7 most frequent failure this week.

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

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

Repository breakdown:

• autoland: 50
• mozilla-central: 8
• try: 25

Platform breakdown:

• android-em-7-0-x86_64: 45
• android-em-7-0-x86_64-shippable: 16
• linux1804-64-tsan: 1
• macosx1014-64-qr: 1
• macosx1014-64: 3
• linux1804-64: 11
• windows10-64-qr: 2
• windows10-64: 2
• linux1804-64-asan-qr: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1660357&startday=2020-08-17&endday=2020-08-23&tree=all

Comment 7

[James Willcox (:snorp) ([e-mail]) (he/him)]

Attachment: Bug 1660357 - Disable PanZoomControllerTest.touchEventForResult for intermittents

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/a4eaf6ae6775
Disable PanZoomControllerTest.touchEventForResult for intermittents r=geckoview-reviewers,agi

Comment 9

[Cosmin Sabou [:CosminS]]

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

Comment 10

[Intermittent Failures Robot]

22 failures in 652 pushes (0.034 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-central: 3
• try: 6

Platform breakdown:

• android-em-7-0-x86_64: 18
• android-em-7-0-x86_64-shippable: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1660357&startday=2020-08-24&endday=2020-08-24&tree=all

Comment 11

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-beta/rev/8a7b617287b2

Comment 12

[Ryan VanderMeulen [:RyanVM]]

Backed out because the regressing bug was backed out from Beta.
https://hg.mozilla.org/releases/mozilla-beta/rev/951e3bfe1f183f82551dc7a0b06170c70c19bcf3

Comment 13

[Intermittent Failures Robot]

22 failures in 4046 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

This is the #45 most frequent failure this week.

Repository breakdown:

• autoland: 13
• mozilla-central: 3
• try: 6

Platform breakdown:

• android-em-7-0-x86_64: 18
• android-em-7-0-x86_64-shippable: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1660357&startday=2020-08-24&endday=2020-08-30&tree=all

Comment 14

[Hiroyuki Ikezoe (:hiro)]

I finally managed to make this test stable! https://treeherder.mozilla.org/#/jobs?repo=try&selectedTaskRun=GihcV_18SSKv2yqbQ6qAfA.1&revision=493b7b1cf508a451ae760c46698bad26a0530291

Comment 15

[Hiroyuki Ikezoe (:hiro)]

Attachment: Bug 1660357 - Drop explicit setting dom.visualviewport.enabled pref. r?#geckoview-reviewers

It's been enabled by default.

Comment 16

[Hiroyuki Ikezoe (:hiro)]

Attachment: Bug 1660357 - Do flushApzRepaints and wait for "apz-repaints-flushed" notification before running each test in PanZoomControllerTest.kt. r?agi!,botond!

What flushApzRepaints does is basically same as what promiseApzRepaintsFlushed
does in apz_test_utils.js, calling nsIDOMWindowUtils.flushApzRepaints and waiting
for "apz-repaints-flushed" in the target content document.

Depends on D93047

Comment 17

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/12e87fb75925
Drop explicit setting dom.visualviewport.enabled pref. r=geckoview-reviewers,agi
https://hg.mozilla.org/integration/autoland/rev/8a2b94cca757
Do flushApzRepaints and wait for "apz-repaints-flushed" notification before running each test in PanZoomControllerTest.kt. r=agi,botond

Comment 18

[Alexandru Michis [:malexandru]]

https://hg.mozilla.org/mozilla-central/rev/12e87fb75925
https://hg.mozilla.org/mozilla-central/rev/8a2b94cca757

Comment 19

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

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