1788125 - Flickering in Firefox, but not in Safari/Chrome, while a page does repeated sync XHRs to reload their product list

Reporter

Description

•

2 years ago

•

In the related webcompat.com report I found that PPG Paints' website is flickering their products while their page loads, but not in Safari or Chrome.

I've done some analysis of their code in comment on the webcompat.com issue.

It's a bit silly, repeating a number of synchronous loads for the same product list while the page loads, emptying and re-filling-out the products HTML markup over and over. But while Chrome and Safari also seem to run the same code (empty product list, call sync XHR, add product list markup), the flickering only happens in Firefox.

Emilio mentioned that it might be related to the refresh driver; possibly WebKit/Blink are less eager to repaint while a sync XHR is happening, thus avoiding the flicker, for instance?

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 1

•

2 years ago

That sounds very possible. Interesting case.
Need to test a bit other browsers, but perhaps we don't need to paint at all during sync XHR.

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 2

•

2 years ago

The behavior is pretty bad in all the browsers if one tries to interact with the page or resize the windows or so.

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 3

•

2 years ago

Attached file Bug 1788125, skip rAF and rAF initiated painting when the relevant window global is suspended, r=emilio — Details

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 4

•

2 years ago

...but the patch doesn't seem to bad with this case. It might not be too horrible in general :)

Phabricator Automation

Updated

•

2 years ago

Assignee: nobody → smaug

Attachment #9292739 - Attachment description: WIP: Bug 1788125, skip rAF and painting when the relevant window global is suspended → Bug 1788125, skip rAF and rAF initiated painting when the relevant window global is suspended, r=emilio

Status: NEW → ASSIGNED

Pulsebot

Comment 5

•

2 years ago

Pushed by opettay@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/f67b16cac9e0
skip rAF and rAF initiated painting when the relevant window global is suspended, r=emilio

Natalia Csoregi [:nataliaCs]

Comment 6

•

2 years ago

Backed out for causing geckoview failures.

[task 2022-09-03T11:11:21.704Z] 11:11:21     INFO -  TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=939
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | Error in asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest):
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test |
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=asyncScriptsSuspendedWhileInactive
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test |
[task 2022-09-03T11:11:59.198Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=689
[task 2022-09-03T11:11:59.198Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[............]
[task 2022-09-03T11:11:59.272Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): Error
[task 2022-09-03T11:11:59.272Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1467)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(D8$$SyntheticClass)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.601 D/GeckoViewConsole( 3356): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"0bd29efa-8ae8-44e0-9434-0f51d5070055","args":{"oldPrefs":{"privacy.reduceTimerPrecision":true,"layout.throttled_frame_rate":1}},"type":"RestorePrefs"}}
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.609 D/EGL_emulation( 9533): eglMakeCurrent: 0x7135179ba080: ver 3 0 (tinfo 0x7135222d2800)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.613 D/GeckoViewContent( 3356): handleEvent: DOMWindowClose
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.616 D/GeckoViewConsole( 3356): onEvent GeckoView:WebExtension:List null
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.621 E/GeckoSessionTestRule( 3356): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest)
[task 2022-09-03T11:11:59.276Z] 11:11:59  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  TEST-INFO took 37543ms

Flags: needinfo?(smaug)

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 7

•

2 years ago

Hmm, whaat is geckoview doing?

Olli Pettay [:smaug][bugs@pettay.fi]

Assignee

Comment 8

•

2 years ago

Now, small tweak, perhaps better luck.

Flags: needinfo?(smaug)

Pulsebot

Comment 9

•

2 years ago

Pushed by opettay@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/716b166ecfe8
skip rAF and rAF initiated painting when the relevant window global is suspended, r=emilio

Marian-Vasile Laza

Comment 10

•

2 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/716b166ecfe8

Status: ASSIGNED → RESOLVED

Closed: 2 years ago

status-firefox106: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 106 Branch

Oana Arbuzov [:oanaarbuzov]

Comment 11

•

2 years ago

I can confirm this is fixed, the product images no longer flicker.

Tested with:
Browser / Version: Firefox Nightly 106.0a1 (2022-09-05)
Operating System: Windows 10 Pro

Status: RESOLVED → VERIFIED

Comment hidden (obsolete)

Alexandru Ionescu (needinfo me) [:alexandrui]

Updated

•

2 years ago

Regressions: 1789619

Alexandru Ionescu (needinfo me) [:alexandrui]

Comment 13

•

2 years ago

(In reply to Natalia Csoregi [:nataliaCs] from comment #6)

Backed out for causing geckoview failures.

[task 2022-09-03T11:11:21.704Z] 11:11:21     INFO -  TEST-START | org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=939
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | Error in asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest):
[task 2022-09-03T11:11:59.192Z] 11:11:59     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.193Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.194Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test |
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=asyncScriptsSuspendedWhileInactive
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SessionLifecycleTest
[task 2022-09-03T11:11:59.195Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.196Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.197Z] 11:11:59     INFO -  org.mozilla.geckoview.test |
[task 2022-09-03T11:11:59.198Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=689
[task 2022-09-03T11:11:59.198Z] 11:11:59     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[............]
[task 2022-09-03T11:11:59.272Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): Error
[task 2022-09-03T11:11:59.272Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2349)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2309)
[task 2022-09-03T11:11:59.273Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.BaseSessionTest.evaluateJS(BaseSessionTest.kt:221)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.SessionLifecycleTest.computeRequestAnimationFrameRate(SessionLifecycleTest.kt:127)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:175)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-09-03T11:11:59.274Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1467)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(D8$$SyntheticClass)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.os.Looper.loop(Looper.java:154)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-09-03T11:11:59.275Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:56.656 E/GeckoSessionTestRule( 3356): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.601 D/GeckoViewConsole( 3356): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"0bd29efa-8ae8-44e0-9434-0f51d5070055","args":{"oldPrefs":{"privacy.reduceTimerPrecision":true,"layout.throttled_frame_rate":1}},"type":"RestorePrefs"}}
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.609 D/EGL_emulation( 9533): eglMakeCurrent: 0x7135179ba080: ver 3 0 (tinfo 0x7135222d2800)
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.613 D/GeckoViewContent( 3356): handleEvent: DOMWindowClose
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.616 D/GeckoViewConsole( 3356): onEvent GeckoView:WebExtension:List null
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  09-03 11:11:58.621 E/GeckoSessionTestRule( 3356): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest)
[task 2022-09-03T11:11:59.276Z] 11:11:59  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-09-03T11:11:59.276Z] 11:11:59     INFO -  TEST-INFO took 37543ms

== Change summary for alert #35308 (as of Wed, 07 Sep 2022 09:17:29 GMT) ==

Improvements:

Ratio	Test	Platform	Options	Absolute values (old vs new)
0.08%	pinterest LastVisualChange	macosx1015-64-shippable-qr	fission warm webrender	4,213.33 -> 4,210.00

For up to date results, see: https://treeherder.mozilla.org/perfherder/alerts?id=35308

Alexandru Ionescu (needinfo me) [:alexandrui]

Updated

•

2 years ago

Keywords: perf-alert

Alexandru Ionescu (needinfo me) [:alexandrui]

Updated

•

2 years ago

Regressions: 1790088

Bogdan Maris, Desktop QA

Updated

•

2 years ago

status-firefox106: fixed → verified

Emilio Cobos Álvarez (:emilio)

Updated

•

2 years ago

Regressions: 1797263

tgn-ff

Updated

•

11 months ago

Regressions: 1834376