1747039 - Intermittent org.mozilla.geckoview.test.OrientationDelegateTest#orientationLockedExistingOrientation | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms

Status: RESOLVED FIXED

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-12-21T10:06:19.026Z] 10:06:19     INFO -  TEST-START | org.mozilla.geckoview.test.OrientationDelegateTest#orientationLockedExistingOrientation
[task 2021-12-21T10:06:53.553Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=829
[task 2021-12-21T10:06:53.553Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-12-21T10:06:53.553Z] 10:06:53     INFO -  org.mozilla.geckoview.test | Error in orientationLockedExistingOrientation(org.mozilla.geckoview.test.OrientationDelegateTest):
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2021-12-21T10:06:53.554Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2183)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2143)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$ExtensionPromise.getValue(GeckoSessionTestRule.java:2110)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.OrientationDelegateTest.lockLandscape(OrientationDelegateTest.kt:81)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.OrientationDelegateTest.orientationLockedExistingOrientation(OrientationDelegateTest.kt:120)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-12-21T10:06:53.555Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1451)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$TNUWVrkQlCzXHIjIbcLih1Q8TRE.run(lambda)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-12-21T10:06:53.556Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test |
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-12-21T10:06:53.557Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=orientationLockedExistingOrientation
[task 2021-12-21T10:06:53.558Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.OrientationDelegateTest
[task 2021-12-21T10:06:53.558Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-12-21T10:06:53.558Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2021-12-21T10:06:53.558Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-12-21T10:06:53.558Z] 10:06:53     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
<...>
[task 2021-12-21T10:06:53.561Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=410
[task 2021-12-21T10:06:53.561Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2021-12-21T10:06:53.561Z] 10:06:53     INFO -  Printing logcat for test:
[task 2021-12-21T10:06:53.616Z] 10:06:53     INFO -  12-21 10:06:18.286 E/GeckoSessionTestRule( 3473): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec orientationLockedExistingOrientation(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2021-12-21T10:06:53.616Z] 10:06:53     INFO -  12-21 10:06:18.286 E/GeckoSessionTestRule( 3473): before prepareStatement orientationLockedExistingOrientation(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2021-12-21T10:06:53.616Z] 10:06:53     INFO -  12-21 10:06:18.338 D/GeckoViewStartup( 3473): onEvent GeckoView:StorageDelegate:Attached
[task 2021-12-21T10:06:53.616Z] 10:06:53     INFO -  12-21 10:06:18.339 I/Gecko   ( 3473): nsWindow[0x716fcf668400]::Create 0x0 [0 0 1 1]
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.349 E/eglCodecCommon( 3473): glUtilsParamSize: unknow param 0x00008caa
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.375 W/webrender::device::gl( 3473): Missing optimized shader source for gpu_cache_update
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.381 E/eglCodecCommon( 3473): glUtilsParamSize: unknow param 0x00008741
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.393 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4235
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.446 I/Gecko   ( 3473): [Parent 3473, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4235
[task 2021-12-21T10:06:53.617Z] 10:06:53     INFO -  12-21 10:06:18.462 D/GeckoViewNavigation( 3473): sessionContextId=null
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.468 D/GeckoViewModule( 3473): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.469 D/GeckoViewNavigation( 3473): onInit
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.470 D/GeckoViewModule( 3473): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.470 D/GeckoViewNavigation( 3473): onEnable
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.471 D/GeckoViewProcessHangMonitor( 3473): onInit
[task 2021-12-21T10:06:53.618Z] 10:06:53     INFO -  12-21 10:06:18.471 D/GeckoViewProcessHangMonitor( 3473): onEnable
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.471 D/GeckoViewModule( 3473): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.472 D/GeckoViewProgress( 3473): onEnable
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.472 D/GeckoSession( 3473): handleMessage GeckoView:PageStart uri=about:blank
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.472 D/GeckoSession( 3473): handleMessage GeckoView:LocationChange uri=about:blank
[task 2021-12-21T10:06:53.619Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 D/GeckoSession( 3473): handleMessage GeckoView:PageStop uri=null
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-12-21T10:06:53.620Z] 10:06:53     INFO -  12-21 10:06:18.472 E/GeckoSessionTestRule( 3473): after prepareStatement
<...>
[task 2021-12-21T10:06:53.640Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1451)
[task 2021-12-21T10:06:53.640Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$TNUWVrkQlCzXHIjIbcLih1Q8TRE.run(lambda)
[task 2021-12-21T10:06:53.640Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-12-21T10:06:53.640Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-12-21T10:06:53.640Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at android.os.Looper.loop(Looper.java:154)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.577 E/GeckoSessionTestRule( 3473): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.581 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"8d179da3-dc9f-4781-8fb8-81d7d23120d0","args":{"oldPrefs":{"dom.screenorientation.allow-lock":false,"full-screen-api.allow-trusted-requests-only":true}},"type":"RestorePrefs"}}
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.595 D/GeckoViewContent( 3473): handleEvent: DOMWindowClose
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.597 D/GeckoViewConsole( 3473): onEvent GeckoView:WebExtension:List null
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  12-21 10:06:52.600 E/GeckoSessionTestRule( 3473): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 orientationLockedExistingOrientation(org.mozilla.geckoview.test.OrientationDelegateTest)
[task 2021-12-21T10:06:53.641Z] 10:06:53  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.OrientationDelegateTest#orientationLockedExistingOrientation | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2021-12-21T10:06:53.641Z] 10:06:53     INFO -  TEST-INFO took 34589ms
[task 2021-12-21T10:06:53.658Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=829
[task 2021-12-21T10:06:53.659Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-12-21T10:06:53.659Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-12-21T10:06:53.659Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=orientationLockNoFullscreen
[task 2021-12-21T10:06:53.660Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.OrientationDelegateTest
[task 2021-12-21T10:06:53.660Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=411
[task 2021-12-21T10:06:53.660Z] 10:06:53     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2021-12-21T10:06:53.661Z] 10:06:53     INFO -  TEST-START | org.mozilla.geckoview.test.OrientationDelegateTest#orientationLockNoFullscreen

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform and build breakdown:

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

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1747039&startday=2021-12-27&endday=2022-01-02&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-central: 3

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1747039&startday=2022-01-03&endday=2022-01-09&tree=all

Comment 4

[Makoto Kato [:m_kato]]

As long as Looking test failures, child process is killed by GC? Then since no content process, waitForMessage will be time-out.

[task 2022-01-08T20:25:46.388Z] 20:25:46     INFO -  01-08 20:25:16.101 D/GeckoViewConsole( 3417): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"0dbdb471-220c-48e3-ba9b-f09335db58be","eval":"this['1ea15f2d-80c7-4f6c-8323-6047b6c52550']"}}
[task 2022-01-08T20:25:46.388Z] 20:25:46     INFO -  01-08 20:25:16.125 D/EGL_emulation( 3417): eglMakeCurrent: 0x79de54053cc0: ver 3 0 (tinfo 0x79de5408d340)
[task 2022-01-08T20:25:46.388Z] 20:25:46     INFO -  01-08 20:25:16.156 I/WindowManager( 1643): Screen frozen for +127ms due to Window{2252274 u0 org.mozilla.geckoview.test/org.mozilla.geckoview.test.GeckoViewTestActivity}
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:18.168 I/art     ( 1643): Starting a blocking GC Explicit
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:18.190 I/art     ( 1643): Explicit concurrent mark sweep GC freed 8153(584KB) AllocSpace objects, 3(60KB) LOS objects, 28% free, 10MB/14MB, paused 272us total 22.861ms
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:41.005 I/ServiceChildProcess(17542): Destroying GeckoServiceChildProcess
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:41.010 D/GeckoViewContent( 3417): observe: ipc:content-shutdown
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:41.010 I/Gecko   ( 3417): [Parent 3417, Unnamed thread 79de66122130] WARNING: waitpid failed pid:17542 errno:10: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:244
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:41.015 I/ActivityManager( 1643): Process org.mozilla.geckoview.test:tab17 (pid 17542) has died
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:41.015 D/ActivityManager( 1643): cleanUpApplicationRecord -- 17542
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): Error
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-01-08T20:25:46.389Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForMessage(GeckoSessionTestRule.java:2183)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.evaluateJS(GeckoSessionTestRule.java:2143)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$ExtensionPromise.getValue(GeckoSessionTestRule.java:2110)
[task 2022-01-08T20:25:46.390Z] 20:25:46     INFO -  01-08 20:25:46.099 E/GeckoSessionTestRule( 3417): 	at org.mozilla.geckoview.test.OrientationDelegateTest.lockLandscape(OrientationDelegateTest.kt:81)
...

Comment 5

[Makoto Kato [:m_kato]]

Ah, I guess this time out occurs since this test is run after orientationLockedAlready. If turned off orientationLockedAlready, this timeout won't occur.

Comment 6

[Intermittent Failures Robot]

68 failures in 527 pushes (0.129 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 61
• mozilla-central: 7

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 42
  • opt: 12
  • debug-isolated-process: 10
  • debug: 20
• android-em-7-0-x86_64-lite-qr: 18
  • debug: 9
  • opt: 9
• android-em-7-0-x86_64-shippable-qr: 2
  • opt: 2
• android-em-7-0-x86_64-shippable-lite-qr: 5
  • opt: 5
• windows10-64-ref-hw-2017-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1747039&startday=2022-01-12&endday=2022-01-12&tree=all

Comment 7

[Makoto Kato [:m_kato]]

Attachment: Bug 1747039 - We should notify Gecko of current orientation information to all process when enableNotifications is called. r=calu

OrientationDelegateTest#orientationLockedExistingOrientation often timed out
since Gecko's orientation information and GeckoView's aren't same value.

If GeckoScreenOrientation.update() is called during mShouldNotify is false,
GeckoView doesn't notify Gecko of current orientation information.
Then, GeckoScreenOrientation.enableNotifications() also doesn't notify it
since mShouldNotify was false. So then, when update() is called by lock
screen, since cached orientation information in GeckoView is same value,
GeckoView doesn't notify it even if Gecko's orientation information and
GeckoView's information aren't same value.

So we should notify Gecko of current orientation information to all processes
when enableNotifications is called.

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/99d93c593aad
We should notify Gecko of current orientation information to all process when enableNotifications is called. r=calu

Comment 9

[Intermittent Failures Robot]

26 failures in 706 pushes (0.037 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 24
• mozilla-central: 2

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1747039&startday=2022-01-13&endday=2022-01-13&tree=all

Comment 10

[Cristian Tuns]

https://hg.mozilla.org/mozilla-central/rev/99d93c593aad

Comment 11

[Intermittent Failures Robot]

110 failures in 3060 pushes (0.036 failures/push) were associated with this bug in the last 7 days.

This is the #9 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: 98
• mozilla-central: 12

Platform and build breakdown:

• android-em-7-0-x86_64-lite-qr: 33
  • opt: 24
  • debug: 9
• android-em-7-0-x86_64-shippable-lite-qr: 16
  • opt: 16
• android-em-7-0-x86_64-shippable-qr: 10
  • opt: 10
• android-em-7-0-x86_64-qr: 50
  • opt: 20
  • debug-isolated-process: 10
  • debug: 20
• windows10-64-ref-hw-2017-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1747039&startday=2022-01-10&endday=2022-01-16&tree=all