Closed
Bug 1763259
Opened 3 years ago
Closed 2 years ago
Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#paste[#contenteditable] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
Categories
(GeckoView :: General, defect, P5)
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=373527920&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dVXnYXd4T0eoGgJ6kzT7tg/runs/0/artifacts/public/logs/live_backing.log
[task 2022-04-05T22:14:18.026Z] 22:14:18 INFO - TEST-START | org.mozilla.geckoview.test.SelectionActionDelegateTest#paste[#contenteditable]
[task 2022-04-05T22:14:48.652Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=884
[task 2022-04-05T22:14:48.653Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-04-05T22:14:48.653Z] 22:14:48 INFO - org.mozilla.geckoview.test | Error in paste[#contenteditable](org.mozilla.geckoview.test.SelectionActionDelegateTest):
[task 2022-04-05T22:14:48.653Z] 22:14:48 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-05T22:14:48.654Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-05T22:14:48.654Z] 22:14:48 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-05T22:14:48.655Z] 22:14:48 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-05T22:14:48.655Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-05T22:14:48.655Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-05T22:14:48.656Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1522)
[task 2022-04-05T22:14:48.656Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1497)
[task 2022-04-05T22:14:48.657Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:181)
[task 2022-04-05T22:14:48.657Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-04-05T22:14:48.657Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$testThat(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.658Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:165)
[task 2022-04-05T22:14:48.658Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.659Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.withClipboard(SelectionActionDelegateTest.kt:368)
[task 2022-04-05T22:14:48.659Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$withClipboard(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.660Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.660Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.661Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.assumingEditable(SelectionActionDelegateTest.kt:393)
[task 2022-04-05T22:14:48.661Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.paste(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.661Z] 22:14:48 INFO - org.mozilla.geckoview.test |
[task 2022-04-05T22:14:48.662Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-04-05T22:14:48.662Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=paste[#contenteditable]
[task 2022-04-05T22:14:48.662Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-04-05T22:14:48.663Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-05T22:14:48.663Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-05T22:14:48.663Z] 22:14:48 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-05T22:14:48.664Z] 22:14:48 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-05T22:14:48.664Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-05T22:14:48.664Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-05T22:14:48.665Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1522)
[task 2022-04-05T22:14:48.665Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1497)
[task 2022-04-05T22:14:48.666Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:181)
[task 2022-04-05T22:14:48.666Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-04-05T22:14:48.666Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$testThat(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.667Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:165)
[task 2022-04-05T22:14:48.667Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.667Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.withClipboard(SelectionActionDelegateTest.kt:368)
[task 2022-04-05T22:14:48.668Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$withClipboard(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.668Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.668Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.668Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.assumingEditable(SelectionActionDelegateTest.kt:393)
[task 2022-04-05T22:14:48.669Z] 22:14:48 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.SelectionActionDelegateTest.paste(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.669Z] 22:14:48 INFO - org.mozilla.geckoview.test |
[task 2022-04-05T22:14:48.669Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=582
[task 2022-04-05T22:14:48.669Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-04-05T22:14:48.669Z] 22:14:48 INFO - Printing logcat for test:
[task 2022-04-05T22:14:48.729Z] 22:14:48 INFO - 04-05 22:14:17.949 E/GeckoSessionTestRule( 3485): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec paste[#contenteditable](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-04-05T22:14:48.730Z] 22:14:48 INFO - 04-05 22:14:17.949 E/GeckoSessionTestRule( 3485): before prepareStatement paste[#contenteditable](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-04-05T22:14:48.730Z] 22:14:48 INFO - 04-05 22:14:17.950 D/LifecycleMonitor( 3485): Lifecycle status change: androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@b036250 in: STOPPED
[task 2022-04-05T22:14:48.731Z] 22:14:48 INFO - 04-05 22:14:17.950 D/GeckoViewStartup( 3485): onEvent GeckoView:StorageDelegate:Attached
[task 2022-04-05T22:14:48.732Z] 22:14:48 INFO - 04-05 22:14:17.950 V/ActivityScenario( 3485): Activity lifecycle changed event received but ignored because the intent does not match. startActivityIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] cmp=org.mozilla.geckoview.test/.GeckoViewTestActivity }, activity.getIntent()=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10008000 cmp=org.mozilla.geckoview.test/androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity (has extras) }, activity=androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@b036250
[task 2022-04-05T22:14:48.733Z] 22:14:48 INFO - 04-05 22:14:17.950 I/Gecko ( 3485): nsWindow[0x7b83c9d3a100]::Create 0x0 [0 0 1 1]
[task 2022-04-05T22:14:48.733Z] 22:14:48 INFO - 04-05 22:14:17.957 I/Gecko ( 3485): [Parent 3485, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4147
[task 2022-04-05T22:14:48.734Z] 22:14:48 INFO - 04-05 22:14:17.958 I/Gecko ( 3485): nsWindow[0x7b83c9d3a100]::Resize [0.000000 0.000000 400.000000 400.000000] (repaint 0)
[task 2022-04-05T22:14:48.734Z] 22:14:48 INFO - 04-05 22:14:17.958 I/Gecko ( 3485): nsWindow: 0x7b83c9d3a100 OnSizeChanged [400 400]
[task 2022-04-05T22:14:48.734Z] 22:14:48 INFO - 04-05 22:14:17.958 I/Gecko ( 3485): [Parent 3485, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4147
[task 2022-04-05T22:14:48.735Z] 22:14:48 INFO - 04-05 22:14:17.973 D/GeckoViewNavigation( 3485): sessionContextId=null
[task 2022-04-05T22:14:48.735Z] 22:14:48 INFO - 04-05 22:14:17.983 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.735Z] 22:14:48 INFO - 04-05 22:14:18.015 W/webrender::device::gl(22522): Missing optimized shader source for gpu_cache_update
[task 2022-04-05T22:14:48.736Z] 22:14:48 INFO - 04-05 22:14:18.023 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008741
[task 2022-04-05T22:14:48.736Z] 22:14:48 INFO - 04-05 22:14:18.032 D/EGL_emulation(22522): eglMakeCurrent: 0x7b842adb3e20: ver 3 0 (tinfo 0x7b84356b43e0)
[task 2022-04-05T22:14:48.737Z] 22:14:48 INFO - 04-05 22:14:18.032 D/GeckoViewModule( 3485): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2022-04-05T22:14:48.737Z] 22:14:48 INFO - 04-05 22:14:18.034 D/GeckoViewSelectionActionDelegate[C](14265): handleEvent: visibilitychange
[task 2022-04-05T22:14:48.737Z] 22:14:48 INFO - 04-05 22:14:18.034 D/GeckoViewNavigation( 3485): onInit
[task 2022-04-05T22:14:48.738Z] 22:14:48 INFO - 04-05 22:14:18.035 D/GeckoViewModule( 3485): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-04-05T22:14:48.738Z] 22:14:48 INFO - 04-05 22:14:18.036 D/GeckoViewNavigation( 3485): onEnable
[task 2022-04-05T22:14:48.738Z] 22:14:48 INFO - 04-05 22:14:18.037 D/GeckoViewProcessHangMonitor( 3485): onInit
[task 2022-04-05T22:14:48.739Z] 22:14:48 INFO - 04-05 22:14:18.037 D/GeckoViewProcessHangMonitor( 3485): onEnable
[task 2022-04-05T22:14:48.739Z] 22:14:48 INFO - 04-05 22:14:18.037 D/GeckoViewModule( 3485): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-04-05T22:14:48.739Z] 22:14:48 INFO - 04-05 22:14:18.039 D/GeckoViewProgress( 3485): onEnable
[task 2022-04-05T22:14:48.740Z] 22:14:48 INFO - 04-05 22:14:18.039 D/GeckoSession( 3485): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-04-05T22:14:48.740Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-04-05T22:14:48.741Z] 22:14:48 INFO - 04-05 22:14:18.039 D/GeckoSession( 3485): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-04-05T22:14:48.742Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-04-05T22:14:48.742Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-04-05T22:14:48.743Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-05T22:14:48.744Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-05T22:14:48.744Z] 22:14:48 INFO - 04-05 22:14:18.039 D/GeckoSession( 3485): handleMessage GeckoView:PageStop uri=null
[task 2022-04-05T22:14:48.745Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-04-05T22:14:48.745Z] 22:14:48 INFO - 04-05 22:14:18.039 E/GeckoSessionTestRule( 3485): after prepareStatement
[task 2022-04-05T22:14:48.745Z] 22:14:48 INFO - 04-05 22:14:18.040 D/GeckoViewModule( 3485): registerListener GeckoView:FlushSessionState
[task 2022-04-05T22:14:48.745Z] 22:14:48 INFO - 04-05 22:14:18.042 D/GeckoViewSettings( 3485): onInit
[task 2022-04-05T22:14:48.746Z] 22:14:48 INFO - 04-05 22:14:18.042 D/GeckoViewModule( 3485): registerListener ["GeckoView:GetUserAgent"]
[task 2022-04-05T22:14:48.747Z] 22:14:48 INFO - 04-05 22:14:18.043 D/GeckoViewSettings( 3485): onSettingsUpdate: {"chromeUri":null,"screenId":0,"userAgentOverride":null,"allowJavascript":true,"userAgentMode":0,"viewportMode":0,"useTrackingProtection":false,"suspendMediaWhenInactive":false,"usePrivateMode":false,"unsafeSessionContextId":null,"displayMode":0,"sessionContextId":null,"fullAccessibilityTree":false,"isPopup":false}
[task 2022-04-05T22:14:48.747Z] 22:14:48 INFO - 04-05 22:14:18.044 D/GeckoViewModule( 3485): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-04-05T22:14:48.747Z] 22:14:48 INFO - 04-05 22:14:18.045 D/GeckoViewModule( 3485): registerListener ["ContentBlocking:RequestLog"]
[task 2022-04-05T22:14:48.747Z] 22:14:48 INFO - 04-05 22:14:18.046 D/GeckoViewSettings[C](14265): receiveMessage: SettingsUpdate
[task 2022-04-05T22:14:48.748Z] 22:14:48 INFO - 04-05 22:14:18.046 D/GeckoViewMediaControl( 3485): onEnable
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.047 D/GeckoViewModule( 3485): registerListener ["GeckoView:MediaSession:Play","GeckoView:MediaSession:Pause","GeckoView:MediaSession:Stop","GeckoView:MediaSession:NextTrack","GeckoView:MediaSession:PrevTrack","GeckoView:MediaSession:SeekForward","GeckoView:MediaSession:SeekBackward","GeckoView:MediaSession:SkipAd","GeckoView:MediaSession:SeekTo","GeckoView:MediaSession:MuteAudio"]
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.050 I/GeckoSession( 3485): zerdatime 728850 - chrome startup finished
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.050 I/Gecko ( 3485): nsWindow[0x7b83c9d3a100]::Show 1
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.053 I/Gecko ( 3485): nsWindow[0x7b83c9d3a100]::Resize [0.000000 0.000000 400.000000 400.000000] (repaint 0)
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.054 W/GeckoEditableChild(14265): No editable parent
[task 2022-04-05T22:14:48.749Z] 22:14:48 INFO - 04-05 22:14:18.056 D/GeckoViewXUL( 3485): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-04-05T22:14:48.750Z] 22:14:48 INFO - 04-05 22:14:18.057 D/GeckoViewModule( 3485): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-04-05T22:14:48.750Z] 22:14:48 INFO - 04-05 22:14:18.058 D/GeckoViewNavigation( 3485): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-04-05T22:14:48.750Z] 22:14:48 INFO - 04-05 22:14:18.078 D/GeckoViewProgress( 3485): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2022-04-05T22:14:48.751Z] 22:14:48 INFO - 04-05 22:14:18.078 D/GeckoViewProgress( 3485): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2022-04-05T22:14:48.751Z] 22:14:48 INFO - 04-05 22:14:18.078 D/GeckoViewProgress( 3485): ProgressTracker start http://localhost:4245/assets/www/inputs.html
[task 2022-04-05T22:14:48.751Z] 22:14:48 INFO - 04-05 22:14:18.079 D/GeckoViewProgress( 3485): ProgressTracker updateProgress
[task 2022-04-05T22:14:48.751Z] 22:14:48 INFO - 04-05 22:14:18.079 D/GeckoViewProgress( 3485): ProgressTracker updateProgress data={"prev":0,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2022-04-05T22:14:48.752Z] 22:14:48 INFO - 04-05 22:14:18.079 D/GeckoSession( 3485): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-04-05T22:14:48.752Z] 22:14:48 INFO - 04-05 22:14:18.080 D/GeckoViewProgress( 3485): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2022-04-05T22:14:48.752Z] 22:14:48 INFO - 04-05 22:14:18.080 D/GeckoSession( 3485): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/inputs.html
[task 2022-04-05T22:14:48.752Z] 22:14:48 INFO - 04-05 22:14:18.084 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.753Z] 22:14:48 INFO - 04-05 22:14:18.091 I/art (24767): Late-enabling -Xcheck:jni
[task 2022-04-05T22:14:48.753Z] 22:14:48 INFO - 04-05 22:14:18.092 W/art (24767): Unexpected CPU variant for X86 using defaults: x86_64
[task 2022-04-05T22:14:48.753Z] 22:14:48 INFO - 04-05 22:14:18.094 I/ActivityManager( 1644): Start proc 24767:org.mozilla.geckoview.test:tab7/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab7
[task 2022-04-05T22:14:48.754Z] 22:14:48 INFO - 04-05 22:14:18.095 D/ServiceAllocator( 3485): org.mozilla.gecko.process.GeckoChildProcessServices$tab7 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-04-05T22:14:48.754Z] 22:14:48 INFO - 04-05 22:14:18.105 I/MultiDex(24767): VM with version 2.1.0 has multidex support
[task 2022-04-05T22:14:48.754Z] 22:14:48 INFO - 04-05 22:14:18.105 I/MultiDex(24767): Installing application
[task 2022-04-05T22:14:48.754Z] 22:14:48 INFO - 04-05 22:14:18.105 I/MultiDex(24767): VM has multidex support, MultiDex support library is disabled.
[task 2022-04-05T22:14:48.755Z] 22:14:48 INFO - 04-05 22:14:18.106 I/ServiceChildProcess(24767): onCreate
[task 2022-04-05T22:14:48.755Z] 22:14:48 INFO - 04-05 22:14:18.107 D/GeckoThread(24767): State changed to LAUNCHED
[task 2022-04-05T22:14:48.755Z] 22:14:48 INFO - 04-05 22:14:18.108 I/GeckoThread(24767): preparing to run Gecko
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.110 D/ServiceAllocator( 3485): org.mozilla.gecko.process.GeckoChildProcessServices$tab7 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_CRASHREPORTER=1
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_CRASHREPORTER_SHUTDOWN=1
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: XPCOM_DEBUG_BREAK=stack
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_DISABLE_NONLOCAL_CONNECTIONS=1
[task 2022-04-05T22:14:48.756Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_IN_AUTOMATION=1
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: R_LOG_VERBOSE=1
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: R_LOG_LEVEL=6
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: R_LOG_DESTINATION=stderr
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_WEBRENDER=1
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.111 D/GeckoThread(24767): env var: MOZ_FORCE_ENABLE_FISSION=1
[task 2022-04-05T22:14:48.757Z] 22:14:48 INFO - 04-05 22:14:18.112 D/GeckoThread(24767): State changed to MOZGLUE_READY
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.112 I/GeckoLoader(24767): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.112 I/GeckoLoader(24767): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.112 E/GeckoLibLoad(24767): Load sqlite start
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.113 E/GeckoLibLoad(24767): Load sqlite done
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.113 I/GeckoLoader(24767): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-04-05T22:14:48.758Z] 22:14:48 INFO - 04-05 22:14:18.113 E/GeckoLibLoad(24767): Load nss start
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.113 E/GeckoLibLoad(24767): Load nss done
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.113 I/GeckoLoader(24767): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.122 E/GeckoLibLoad(24767): Loaded libs in 8.872548ms total, 0ms(0ms) user, 0ms(10ms) system, 0(0) faults
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.122 D/GeckoThread(24767): State changed to LIBS_READY
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.122 W/GeckoThread(24767): zerdatime 728923 - runGecko
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.124 D/GeckoViewXUL( 3485): WillChangeBrowserRemoteness
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.130 D/GeckoViewSelectionActionDelegate[C](14265): handleEvent: visibilitychange
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.131 D/GeckoViewAutoFill[C](14265): handleEvent: pagehide
[task 2022-04-05T22:14:48.759Z] 22:14:48 INFO - 04-05 22:14:18.131 D/GeckoViewAutofill(14265): Clearing auto-fill
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.136 D/GeckoViewXUL( 3485): DidChangeBrowserRemoteness
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.136 D/GeckoViewNavigation( 3485): sessionContextId=null
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.142 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.143 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.146 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.166 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.760Z] 22:14:48 INFO - 04-05 22:14:18.170 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.761Z] 22:14:48 INFO - 04-05 22:14:18.171 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.761Z] 22:14:48 INFO - 04-05 22:14:18.173 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.761Z] 22:14:48 INFO - 04-05 22:14:18.182 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.761Z] 22:14:48 INFO - 04-05 22:14:18.188 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.761Z] 22:14:48 INFO - 04-05 22:14:18.189 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.192 W/webrender::device::gl(22522): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.196 D/GeckoThread(24767): State changed to RUNNING
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.231 D/GeckoViewStartup(24767): observe: content-process-ready-for-script
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.236 D/GeckoViewConsole(24767): enabled = true
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.270 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.762Z] 22:14:48 INFO - 04-05 22:14:18.288 D/GeckoViewXUL( 3485): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-04-05T22:14:48.763Z] 22:14:48 INFO - 04-05 22:14:18.292 I/Gecko (24767): [Child 24767, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2022-04-05T22:14:48.763Z] 22:14:48 INFO - 04-05 22:14:18.292 I/Gecko (24767): [Child 24767, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2022-04-05T22:14:48.763Z] 22:14:48 INFO - 04-05 22:14:18.292 I/Gecko (24767): [Child 24767, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2022-04-05T22:14:48.763Z] 22:14:48 INFO - 04-05 22:14:18.300 E/eglCodecCommon(22522): glUtilsParamSize: unknow param 0x00008caa
[task 2022-04-05T22:14:48.764Z] 22:14:48 INFO - 04-05 22:14:18.301 D/GeckoViewSelectionActionDelegate[C](24767): handleEvent: visibilitychange
[task 2022-04-05T22:14:48.764Z] 22:14:48 INFO - 04-05 22:14:18.303 D/GeckoViewAutoFill[C](24767): handleEvent: pagehide
[task 2022-04-05T22:14:48.764Z] 22:14:48 INFO - 04-05 22:14:18.306 D/GeckoViewAutofill(24767): Clearing auto-fill
[task 2022-04-05T22:14:48.764Z] 22:14:48 INFO - 04-05 22:14:18.313 W/Isolated Web Content(24767): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:4245/assets/www/inputs.html" line: 0}]
[task 2022-04-05T22:14:48.765Z] 22:14:48 INFO - 04-05 22:14:18.313 D/GeckoViewNavigation( 3485): onLocationChange
[task 2022-04-05T22:14:48.765Z] 22:14:48 INFO - 04-05 22:14:18.315 D/GeckoSession( 3485): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/inputs.html
[task 2022-04-05T22:14:48.765Z] 22:14:48 INFO - 04-05 22:14:18.316 D/GeckoViewProgress( 3485): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-04-05T22:14:48.765Z] 22:14:48 INFO - 04-05 22:14:18.316 D/GeckoViewProgress( 3485): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-04-05T22:14:48.765Z] 22:14:48 INFO - 04-05 22:14:18.316 D/GeckoViewProgress( 3485): ProgressTracker changeLocation http://localhost:4245/assets/www/inputs.html
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:18.316 D/GeckoViewProgress( 3485): onSecurityChange
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:18.317 D/GeckoSession( 3485): handleMessage GeckoView:SecurityChanged uri=null
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:19.082 I/Gecko ( 3485): nsWindow 0x7b83c9d0ea00 destructor
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:19.082 I/Gecko ( 3485): nsWindow 0x7b83c9d0e100 destructor
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:19.083 I/Gecko ( 3485): nsWindow 0x7b83c9d0e400 destructor
[task 2022-04-05T22:14:48.766Z] 22:14:48 INFO - 04-05 22:14:19.083 I/Gecko ( 3485): nsWindow 0x7b83c9d11700 destructor
[task 2022-04-05T22:14:48.767Z] 22:14:48 INFO - 04-05 22:14:19.083 I/Gecko ( 3485): nsWindow 0x7b83c9d0ed00 destructor
[task 2022-04-05T22:14:48.767Z] 22:14:48 INFO - 04-05 22:14:19.084 I/Gecko ( 3485): nsWindow 0x7b83c9d10b00 destructor
[task 2022-04-05T22:14:48.767Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): Error
[task 2022-04-05T22:14:48.767Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-05T22:14:48.767Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1741)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1522)
[task 2022-04-05T22:14:48.768Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1497)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:181)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.testThat(SelectionActionDelegateTest.kt:271)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$testThat(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:165)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.769Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.withClipboard(SelectionActionDelegateTest.kt:368)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.access$withClipboard(SelectionActionDelegateTest.kt:37)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:164)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest$paste$1.invoke(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.assumingEditable(SelectionActionDelegateTest.kt:393)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.SelectionActionDelegateTest.paste(SelectionActionDelegateTest.kt:163)
[task 2022-04-05T22:14:48.770Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at java.lang.reflect.Method.invoke(Native Method)
[task 2022-04-05T22:14:48.771Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2022-04-05T22:14:48.771Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2022-04-05T22:14:48.771Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2022-04-05T22:14:48.771Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$org-mozilla-geckoview-test-rule-GeckoSessionTestRule$2(GeckoSessionTestRule.java:1451)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda0.run(Unknown Source)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2022-04-05T22:14:48.772Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.os.Looper.loop(Looper.java:154)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at java.lang.reflect.Method.invoke(Native Method)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.318 E/GeckoSessionTestRule( 3485): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-04-05T22:14:48.773Z] 22:14:48 INFO - 04-05 22:14:48.320 D/EGL_emulation(22522): eglMakeCurrent: 0x7b842adb3e20: ver 3 0 (tinfo 0x7b84356b43e0)
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - 04-05 22:14:48.323 D/GeckoViewContent( 3485): handleEvent: DOMWindowClose
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - 04-05 22:14:48.324 D/GeckoViewConsole( 3485): onEvent GeckoView:WebExtension:List null
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - 04-05 22:14:48.330 E/GeckoSessionTestRule( 3485): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 paste[#contenteditable](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-04-05T22:14:48.774Z] 22:14:48 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SelectionActionDelegateTest#paste[#contenteditable] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - TEST-INFO took 30705ms
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=884
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-04-05T22:14:48.774Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=multipleActions[#contenteditable]
[task 2022-04-05T22:14:48.775Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-04-05T22:14:48.775Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=583
[task 2022-04-05T22:14:48.775Z] 22:14:48 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-04-05T22:14:48.775Z] 22:14:48 INFO - TEST-START | org.mozilla.geckoview.test.SelectionActionDelegateTest#multipleActions[#contenteditable]
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=384566373&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 7•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=397406109&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 9•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•