Closed Bug 1845493 Opened 2 years ago Closed 1 month ago

Intermittent org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive | single tracking bug

Categories

(GeckoView :: General, defect, P5)

All
Android
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2023-07-26T10:31:18.794Z] 10:31:18     INFO -  07-26 10:31:17.307 E/eglCodecCommon(16136): glUtilsParamSize: unknow param 0x00008caa
[task 2023-07-26T10:31:18.794Z] 10:31:18     INFO -  07-26 10:31:17.307 D/GeckoViewProgress( 3448): receiveMessage: MozAfterPaint
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.364 D/GeckoViewProgressDelegate[C](  462): handleEvent: MozAfterPaint
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): Error
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): java.lang.AssertionError: requestAnimationFrame should be called about once a second
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): Expected: a value greater than <450.0>
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448):      but: <447.02000000000004> was less than <450.0>
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2023-07-26T10:31:18.795Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.Assert.assertThat(Assert.java:964)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:77)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:90)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:75)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:916)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:153)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.SessionLifecycleTest.asyncScriptsSuspendedWhileInactive(SessionLifecycleTest.kt:191)
[task 2023-07-26T10:31:18.796Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2023-07-26T10:31:18.797Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0(GeckoSessionTestRule.java:1483)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.$r8$lambda$eGWMAiU-In-hdc_ISYHPMUhHPKA(GeckoSessionTestRule.java)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda1.run(D8$$SyntheticClass)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2023-07-26T10:31:18.798Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at android.os.Looper.loop(Looper.java:154)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.366 E/GeckoSessionTestRule( 3448): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2023-07-26T10:31:18.799Z] 10:31:18     INFO -  07-26 10:31:17.367 D/GeckoViewProgress( 3448): receiveMessage: MozAfterPaint
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.369 D/GeckoViewConsole( 3448): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"cdadd978-48d8-4e34-a4b8-6ae92e0b6dc2","args":{"oldPrefs":{"privacy.reduceTimerPrecision":true,"layout.throttled_frame_rate":1}},"type":"RestorePrefs"}}
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.384 D/EGL_emulation(16136): eglMakeCurrent: 0x7ca156e7d480: ver 3 0 (tinfo 0x7ca14c72a720)
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.385 I/Gecko   ( 3448): nsWindow 0x7ca0ccf26300 destructor
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.390 D/GeckoViewContent( 3448): handleEvent: DOMWindowClose
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.392 D/GeckoViewConsole( 3448): onEvent GeckoView:WebExtension:List null
[task 2023-07-26T10:31:18.800Z] 10:31:18     INFO -  07-26 10:31:17.398 E/GeckoSessionTestRule( 3448): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 asyncScriptsSuspendedWhileInactive(org.mozilla.geckoview.test.SessionLifecycleTest)
[task 2023-07-26T10:31:18.800Z] 10:31:18  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SessionLifecycleTest#asyncScriptsSuspendedWhileInactive | java.lang.AssertionError: requestAnimationFrame should be called about once a second
[task 2023-07-26T10:31:18.801Z] 10:31:18     INFO -  TEST-INFO took 3606ms
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago9 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 9 months ago7 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 months ago1 month ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.