Closed
Bug 1798752
Opened 3 years ago
Closed 3 years ago
Intermittent org.mozilla.geckoview.test.TextInputDelegateTest#inputConnection_deleteSurroundingText[#designmode] | 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: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=395272646&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BJ_nehexRpuqZOl1YDic2Q/runs/0/artifacts/public/logs/live_backing.log
[task 2022-11-02T17:16:37.396Z] 17:16:37 INFO - TEST-START | org.mozilla.geckoview.test.TextInputDelegateTest#inputConnection_deleteSurroundingText[#designmode]
[task 2022-11-02T17:17:08.206Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=962
[task 2022-11-02T17:17:08.206Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-11-02T17:17:08.206Z] 17:17:08 INFO - org.mozilla.geckoview.test | Error in inputConnection_deleteSurroundingText[#designmode](org.mozilla.geckoview.test.TextInputDelegateTest):
[task 2022-11-02T17:17:08.206Z] 17:17:08 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1782)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1538)
[task 2022-11-02T17:17:08.207Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1513)
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:189)
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.TextInputDelegateTest.setupContent(TextInputDelegateTest.kt:407)
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_deleteSurroundingText(TextInputDelegateTest.kt:467)
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test |
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=inputConnection_deleteSurroundingText[#designmode]
[task 2022-11-02T17:17:08.208Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.TextInputDelegateTest
[task 2022-11-02T17:17:08.209Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-11-02T17:17:08.209Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2022-11-02T17:17:08.209Z] 17:17:08 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2022-11-02T17:17:08.209Z] 17:17:08 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2022-11-02T17:17:08.209Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1782)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStops(GeckoSessionTestRule.java:1538)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForPageStop(GeckoSessionTestRule.java:1513)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.waitForPageStop(BaseSessionTest.kt:189)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.TextInputDelegateTest.setupContent(TextInputDelegateTest.kt:407)
[task 2022-11-02T17:17:08.210Z] 17:17:08 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_deleteSurroundingText(TextInputDelegateTest.kt:467)
[task 2022-11-02T17:17:08.211Z] 17:17:08 INFO - org.mozilla.geckoview.test |
[task 2022-11-02T17:17:08.211Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=819
[task 2022-11-02T17:17:08.211Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-11-02T17:17:08.211Z] 17:17:08 INFO - Printing logcat for test:
[task 2022-11-02T17:17:08.815Z] 17:17:08 INFO - 11-02 17:16:37.152 E/GeckoSessionTestRule( 3452): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec inputConnection_deleteSurroundingText[#designmode](org.mozilla.geckoview.test.TextInputDelegateTest)
[task 2022-11-02T17:17:08.815Z] 17:17:08 INFO - 11-02 17:16:37.152 E/GeckoSessionTestRule( 3452): before prepareStatement inputConnection_deleteSurroundingText[#designmode](org.mozilla.geckoview.test.TextInputDelegateTest)
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.189 D/GeckoViewSelectionActionDelegate[C](16063): handleEvent: visibilitychange
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewNavigation( 3452): onDisable
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewProcessHangMonitor( 3452): onDisable
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewClipboardPermissionChild[C](16063): handleEvent: deactivate
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewModule( 3452): unregisterListener
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewProcessHangMonitor( 3452): onDestroy
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewProgress( 3452): onDisable
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.190 D/GeckoViewModule( 3452): unregisterListener
[task 2022-11-02T17:17:08.816Z] 17:17:08 INFO - 11-02 17:16:37.191 D/GeckoViewSelectionAction( 3452): onDisable
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.191 D/GeckoViewModule( 3452): unregisterListener
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.191 D/GeckoViewModule( 3452): unregisterListener
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.191 D/GeckoViewMediaControl( 3452): onDisable
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.191 D/GeckoViewModule( 3452): unregisterListener
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.194 D/GeckoViewSessionStore( 3452): observe browsing-context-discarded
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.196 D/GeckoViewSessionStore( 3452): observe browsing-context-discarded
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.200 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.817Z] 17:17:08 INFO - 11-02 17:16:37.204 D/GeckoViewSelectionActionDelegate[C](16063): handleEvent: visibilitychange
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.204 D/GeckoViewClipboardPermissionChild[C](16063): handleEvent: pagehide
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.205 D/GeckoViewAutoFill[C](16063): handleEvent: pagehide
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.207 D/GeckoViewStartup( 3452): onEvent GeckoView:StorageDelegate:Attached
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.217 D/GeckoViewClipboardPermissionParent( 3452): receiveMessage: DispatcherMessage
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.218 D/GeckoViewActorParent( 3452): receiveMessage window destroyed DispatcherMessage GeckoView:DismissClipboardPermissionRequest
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.228 D/GeckoViewClipboardPermissionParent( 3452): receiveMessage: DispatcherMessage
[task 2022-11-02T17:17:08.818Z] 17:17:08 INFO - 11-02 17:16:37.229 D/GeckoViewActorParent( 3452): receiveMessage window destroyed DispatcherMessage GeckoView:DismissClipboardPermissionRequest
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.232 D/GeckoViewAutoFill( 3452): receiveMessage Clear
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.281 D/GeckoViewNavigation( 3452): sessionContextId=null
[task 2022-11-02T17:17:08.840Z] 17:17:08 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.TextInputDelegateTest#inputConnection_deleteSurroundingText[#designmode] | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2022-11-02T17:17:08.840Z] 17:17:08 INFO - TEST-INFO took 31420ms
[task 2022-11-02T17:17:08.840Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=962
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=inputConnection_largeText[#designmode]
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.TextInputDelegateTest
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=820
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
[task 2022-11-02T17:17:08.841Z] 17:17:08 INFO - TEST-START | org.mozilla.geckoview.test.TextInputDelegateTest#inputConnection_largeText[#designmode]
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.287 D/GeckoViewSessionStore( 3452): observe browsing-context-did-set-embedder
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.294 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.306 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008741
[task 2022-11-02T17:17:08.819Z] 17:17:08 INFO - 11-02 17:16:37.314 D/GeckoViewModule( 3452): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:SetPriorityHint","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.315 D/GeckoViewNavigation( 3452): onInit
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.315 D/GeckoViewModule( 3452): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.315 D/GeckoViewNavigation( 3452): onEnable
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.316 D/GeckoViewProcessHangMonitor( 3452): onInit
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.316 D/GeckoViewProcessHangMonitor( 3452): onEnable
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.316 D/GeckoViewModule( 3452): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-11-02T17:17:08.820Z] 17:17:08 INFO - 11-02 17:16:37.316 D/GeckoViewProgress( 3452): onEnable
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.317 D/GeckoViewModule( 3452): registerListener GeckoView:FlushSessionState
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.318 D/EGL_emulation(16437): eglMakeCurrent: 0x7c5e28c7c260: ver 3 0 (tinfo 0x7c5e1e2c8c20)
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.318 D/GeckoViewSelectionAction( 3452): onEnable
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.318 D/GeckoViewModule( 3452): registerListener ["GeckoView:ExecuteSelectionAction"]
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.319 D/GeckoViewSettings( 3452): onInit
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.319 D/GeckoViewModule( 3452): registerListener ["GeckoView:GetUserAgent"]
[task 2022-11-02T17:17:08.821Z] 17:17:08 INFO - 11-02 17:16:37.319 D/GeckoViewSettings( 3452): 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-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.320 D/GeckoViewModule( 3452): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.320 D/GeckoViewModule( 3452): registerListener ["ContentBlocking:RequestLog"]
[task 2022-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.320 D/GeckoSession( 3452): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.320 E/GeckoSessionTestRule( 3452): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.321 D/GeckoSession( 3452): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-11-02T17:17:08.822Z] 17:17:08 INFO - 11-02 17:16:37.321 D/GeckoViewMediaControl( 3452): onEnable
[task 2022-11-02T17:17:08.823Z] 17:17:08 INFO - 11-02 17:16:37.321 E/GeckoSessionTestRule( 3452): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-11-02T17:17:08.823Z] 17:17:08 INFO - 11-02 17:16:37.321 E/GeckoSessionTestRule( 3452): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-11-02T17:17:08.823Z] 17:17:08 INFO - 11-02 17:16:37.321 E/GeckoSessionTestRule( 3452): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-11-02T17:17:08.823Z] 17:17:08 INFO - 11-02 17:16:37.321 D/GeckoViewModule( 3452): 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-11-02T17:17:08.823Z] 17:17:08 INFO - 11-02 17:16:37.321 D/GeckoSession( 3452): handleMessage GeckoView:PageStop uri=null
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.321 E/GeckoSessionTestRule( 3452): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.321 E/GeckoSessionTestRule( 3452): after prepareStatement
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.326 I/GeckoSession( 3452): zerdatime 1155848 - chrome startup finished
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.334 D/GeckoViewSelectionActionDelegate[C](16063): handleEvent: visibilitychange
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.335 D/GeckoViewClipboardPermissionChild[C](16063): handleEvent: deactivate
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.353 D/GeckoViewSettings[C](16063): receiveMessage: SettingsUpdate
[task 2022-11-02T17:17:08.824Z] 17:17:08 INFO - 11-02 17:16:37.408 W/GeckoEditableChild(16063): No editable parent
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.412 D/GeckoViewConsole( 3452): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"5af2294b-acdc-4c3e-86a2-b5be973d9aae","args":{"oldPrefs":{},"newPrefs":{"dom.select_events.textcontrols.enabled":true}},"type":"SetPrefs"}}
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.418 D/GeckoViewClipboardPermissionParent( 3452): receiveMessage: DispatcherMessage
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.419 D/GeckoSession( 3452): handleMessage: GeckoView:DismissClipboardPermissionRequest
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.421 D/GeckoViewXUL( 3452): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.433 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.439 D/GeckoViewModule( 3452): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-11-02T17:17:08.825Z] 17:17:08 INFO - 11-02 17:16:37.439 D/GeckoViewNavigation( 3452): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.455 D/GeckoViewProgress( 3452): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.455 D/GeckoViewProgress( 3452): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.455 D/GeckoViewProgress( 3452): ProgressTracker start http://localhost:4245/assets/www/inputs.html
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.456 D/GeckoViewProgress( 3452): ProgressTracker updateProgress
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.456 D/GeckoViewProgress( 3452): 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-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.456 D/GeckoSession( 3452): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-11-02T17:17:08.826Z] 17:17:08 INFO - 11-02 17:16:37.457 D/GeckoViewProgress( 3452): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.457 D/GeckoSession( 3452): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/inputs.html
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.481 I/art ( 2686): Late-enabling -Xcheck:jni
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.481 W/art ( 2686): Unexpected CPU variant for X86 using defaults: x86_64
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.484 I/ActivityManager( 1658): Start proc 2686:org.mozilla.geckoview.test:tab29/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab29
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.487 D/ServiceAllocator( 3452): org.mozilla.gecko.process.GeckoChildProcessServices$tab29 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.516 I/MultiDex( 2686): VM with version 2.1.0 has multidex support
[task 2022-11-02T17:17:08.827Z] 17:17:08 INFO - 11-02 17:16:37.517 I/MultiDex( 2686): Installing application
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.517 I/MultiDex( 2686): VM has multidex support, MultiDex support library is disabled.
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.518 I/ServiceChildProcess( 2686): onCreate
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.521 D/GeckoThread( 2686): State changed to LAUNCHED
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.522 I/GeckoThread( 2686): preparing to run Gecko
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.535 D/GeckoThread( 2686): State changed to MOZGLUE_READY
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.535 I/GeckoLoader( 2686): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.536 I/GeckoLoader( 2686): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-11-02T17:17:08.828Z] 17:17:08 INFO - 11-02 17:16:37.537 E/GeckoLibLoad( 2686): Load sqlite start
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.537 E/GeckoLibLoad( 2686): Load sqlite done
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.538 I/GeckoLoader( 2686): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.538 E/GeckoLibLoad( 2686): Load nss start
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.538 E/GeckoLibLoad( 2686): Load nss done
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.538 I/GeckoLoader( 2686): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.548 E/GeckoLibLoad( 2686): Loaded libs in 9.881370ms total, 0ms(0ms) user, 0ms(10ms) system, 0(0) faults
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.548 D/GeckoThread( 2686): State changed to LIBS_READY
[task 2022-11-02T17:17:08.829Z] 17:17:08 INFO - 11-02 17:16:37.548 W/GeckoThread( 2686): zerdatime 1156070 - runGecko
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.572 D/ServiceAllocator( 3452): org.mozilla.gecko.process.GeckoChildProcessServices$tab29 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.583 D/GeckoViewXUL( 3452): WillChangeBrowserRemoteness
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.586 D/GeckoViewSessionStore( 3452): observe browsing-context-did-set-embedder
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.588 D/GeckoViewSessionStore( 3452): observe browsing-context-did-set-embedder
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.589 D/GeckoViewSelectionActionDelegate[C](16063): handleEvent: visibilitychange
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.589 D/GeckoViewClipboardPermissionChild[C](16063): handleEvent: pagehide
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.590 D/GeckoViewAutoFill[C](16063): handleEvent: pagehide
[task 2022-11-02T17:17:08.830Z] 17:17:08 INFO - 11-02 17:16:37.601 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.604 D/GeckoViewXUL( 3452): DidChangeBrowserRemoteness
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.605 D/GeckoViewNavigation( 3452): sessionContextId=null
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.606 D/GeckoThread( 2686): State changed to RUNNING
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.615 D/GeckoViewClipboardPermissionParent( 3452): receiveMessage: DispatcherMessage
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.616 D/GeckoSession( 3452): handleMessage: GeckoView:DismissClipboardPermissionRequest
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.617 D/GeckoViewAutoFill( 3452): receiveMessage Clear
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.633 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.831Z] 17:17:08 INFO - 11-02 17:16:37.645 D/GeckoViewStartup( 2686): observe: content-process-ready-for-script
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.651 D/GeckoViewConsole( 2686): enabled = true
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.715 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.768 D/GeckoViewXUL( 3452): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.845 E/eglCodecCommon(16437): glUtilsParamSize: unknow param 0x00008caa
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.873 D/GeckoViewSelectionActionDelegate[C]( 2686): handleEvent: visibilitychange
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.875 D/GeckoViewClipboardPermissionChild[C]( 2686): handleEvent: pagehide
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.879 D/GeckoViewAutoFill[C]( 2686): handleEvent: pagehide
[task 2022-11-02T17:17:08.832Z] 17:17:08 INFO - 11-02 17:16:37.891 D/GeckoViewClipboardPermissionParent( 3452): receiveMessage: DispatcherMessage
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.891 D/GeckoSession( 3452): handleMessage: GeckoView:DismissClipboardPermissionRequest
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.893 D/GeckoViewAutoFill( 3452): receiveMessage Clear
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.899 D/GeckoViewContentBlocking( 3452): onContentBlockingEvent 8000
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.899 D/GeckoViewContentBlocking( 3452): onContentBlockingEvent matchedList: null
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.899 D/GeckoViewContentBlocking( 3452): onContentBlockingEvent matchedTrackingLists: []
[task 2022-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.917 W/Isolated Web Content( 2686): [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-11-02T17:17:08.833Z] 17:17:08 INFO - 11-02 17:16:37.919 D/GeckoViewNavigation( 3452): onLocationChange
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.920 D/GeckoSession( 3452): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/inputs.html
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.920 D/GeckoViewProgress( 3452): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.920 D/GeckoViewProgress( 3452): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.920 D/GeckoViewProgress( 3452): ProgressTracker changeLocation http://localhost:4245/assets/www/inputs.html
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.921 D/GeckoViewProgress( 3452): onSecurityChange
[task 2022-11-02T17:17:08.834Z] 17:17:08 INFO - 11-02 17:16:37.921 D/GeckoSession( 3452): handleMessage GeckoView:SecurityChanged uri=null
[task 2022-11-02T17:17:08.835Z] 17:17:08 INFO - 11-02 17:16:40.198 D/ServiceAllocator( 3452): org.mozilla.gecko.process.GeckoChildProcessServices$tab0 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2022-11-02T17:17:08.835Z] 17:17:08 INFO - 11-02 17:17:07.923 E/GeckoSessionTestRule( 3452): Error
[task 2022-11-02T17:17:08.835Z] 17:17:08 INFO - 11-02 17:17:07.923 E/GeckoSessionTestRule( 3452): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
| 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
You need to log in
before you can comment on or make changes to this bug.
Description
•