1718734 - Intermittent org.mozilla.geckoview.test.TextInputDelegateTest#inputConnection_bug1593683[#contenteditable] | java.lang.AssertionError: commit abc

Status: RESOLVED DUPLICATE of bug 1716696

(GeckoView :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-06-30T07:52:24.462Z] 07:52:24     INFO -  TEST-START | org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_bug1593683[#contenteditable]
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=818
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | Error in inputConnection_bug1593683[#contenteditable](org.mozilla.geckoview.test.TextInputDelegateTest):
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | java.lang.AssertionError: commit abc
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | Expected: "bar"
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test |      but: was "barfoo"
[task 2021-06-30T07:52:24.926Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.Assert.assertThat(Assert.java:956)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:826)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:114)
[task 2021-06-30T07:52:24.927Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText(TextInputDelegateTest.kt:347)
[task 2021-06-30T07:52:24.928Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText(TextInputDelegateTest.kt:357)
[task 2021-06-30T07:52:24.928Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText$default(TextInputDelegateTest.kt:350)
[task 2021-06-30T07:52:24.928Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_bug1593683(TextInputDelegateTest.kt:964)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-06-30T07:52:24.929Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1317)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$cmP_QJg-X16GIlt8KIQd32egmfU.run(lambda)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-30T07:52:24.930Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test |
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=inputConnection_bug1593683[#contenteditable]
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.TextInputDelegateTest
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=java.lang.AssertionError: commit abc
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test | Expected: "bar"
[task 2021-06-30T07:52:24.931Z] 07:52:24     INFO -  org.mozilla.geckoview.test |      but: was "barfoo"
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.Assert.assertThat(Assert.java:956)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:826)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:114)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText(TextInputDelegateTest.kt:347)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText(TextInputDelegateTest.kt:357)
[task 2021-06-30T07:52:24.932Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.assertText$default(TextInputDelegateTest.kt:350)
[task 2021-06-30T07:52:24.933Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_bug1593683(TextInputDelegateTest.kt:964)
[task 2021-06-30T07:52:24.933Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-30T07:52:24.933Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2021-06-30T07:52:24.933Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2021-06-30T07:52:24.933Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1317)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$cmP_QJg-X16GIlt8KIQd32egmfU.run(lambda)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.handleCallback(Handler.java:751)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2021-06-30T07:52:24.934Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test |
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=688
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2021-06-30T07:52:24.935Z] 07:52:24     INFO -  Printing logcat for test:
[task 2021-06-30T07:52:25.116Z] 07:52:25     INFO -  06-30 08:52:24.352 E/GeckoSessionTestRule( 3152): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec inputConnection_bug1593683[#contenteditable](org.mozilla.geckoview.test.TextInputDelegateTest)
[task 2021-06-30T07:52:25.116Z] 07:52:25     INFO -  06-30 08:52:24.352 E/GeckoSessionTestRule( 3152): before prepareStatement inputConnection_bug1593683[#contenteditable](org.mozilla.geckoview.test.TextInputDelegateTest)
[task 2021-06-30T07:52:25.116Z] 07:52:25     INFO -  06-30 08:52:24.352 D/GeckoViewMedia[C](26774): onDisable
[task 2021-06-30T07:52:25.116Z] 07:52:25     INFO -  06-30 08:52:24.353 D/GeckoViewMediaControl[C](26774): onDisable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.354 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.354 D/GeckoViewSelectionActionDelegate[C](26774): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.354 D/GeckoViewAutofill[C](26774): handleEvent: pagehide
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.354 D/GeckoViewAutofill(26774): Clearing auto-fill
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.355 D/GeckoViewAutofill[C](26774): handleEvent: focusout
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 D/GeckoViewSelectionActionDelegate[C](26774): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 D/GeckoViewAutofill[C](26774): handleEvent: pagehide
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 E/GeckoConsole( 3152): [JavaScript Error: "TypeError: can't access property "ownerGlobal", this.browser is null" {file: "resource://gre/modules/GeckoViewActorParent.jsm" line: 22}]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 E/GeckoConsole( 3152): get window@resource://gre/modules/GeckoViewActorParent.jsm:22:5
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 E/GeckoConsole( 3152): receiveMessage@resource://gre/modules/GeckoViewActorParent.jsm:31:5
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 D/GeckoViewSelectionActionDelegate[C](26774): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.356 D/GeckoViewAutofill[C](26774): handleEvent: pagehide
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.360 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.369 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008741
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.377 D/EGL_emulation( 3152): eglMakeCurrent: 0x7ef84774f9a0: ver 3 0 (tinfo 0x7ef847759d80)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.378 I/ServiceChildProcess(26774): Destroying GeckoServiceChildProcess
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.379 I/art     (26774): System.exit called, status: 0
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.379 I/AndroidRuntime(26774): VM exiting with result code 0, cleanup skipped.
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.387 D/GeckoViewNavigation( 3152): sessionContextId=null
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.389 D/GeckoViewModule( 3152): 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-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewModule( 3152): enableQueuing true
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewModule( 3152): registerListener ["GeckoView:MediaObserve","GeckoView:MediaUnobserve","GeckoView:MediaPlay","GeckoView:MediaPause","GeckoView:MediaSeek","GeckoView:MediaSetVolume","GeckoView:MediaSetMuted","GeckoView:MediaSetPlaybackRate"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewNavigation( 3152): onInit
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewModule( 3152): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewNavigation( 3152): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewProcessHangMonitor( 3152): onInit
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewProcessHangMonitor( 3152): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.390 D/GeckoViewModule( 3152): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoViewProgress( 3152): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoSession( 3152): handleMessage GeckoView:PageStart uri=about:blank
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoSession( 3152): handleMessage GeckoView:LocationChange uri=about:blank
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoSession( 3152): handleMessage GeckoView:PageStop uri=null
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 E/GeckoSessionTestRule( 3152): after prepareStatement
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoViewModule( 3152): registerListener GeckoView:FlushSessionState
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoViewSettings( 3152): onInit
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoViewModule( 3152): registerListener ["GeckoView:GetUserAgent"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.391 D/GeckoViewSettings( 3152): 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 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.392 D/GeckoViewModule( 3152): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.392 D/GeckoViewModule( 3152): registerListener ["ContentBlocking:RequestLog"]
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.392 D/GeckoViewModule( 3152): enableQueuing true
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.392 D/GeckoViewMediaControl( 3152): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.392 D/GeckoViewModule( 3152): 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 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.393 I/GeckoSession( 3152): zerdatime 513892 - chrome startup finished
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.394 I/Gecko   ( 3152): [Parent 3152, Unnamed thread 7ef835122ab0] WARNING: waitpid failed pid:26774 errno:10: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:238
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.395 D/GeckoViewSelectionActionDelegate[C](23754): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.395 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"ada680dd-3757-4b9a-ace1-1b713f5fb9af","args":{"oldPrefs":{},"newPrefs":{"dom.select_events.textcontrols.enabled":true}},"type":"SetPrefs"}}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.396 W/GeckoEditableChild(23754): No editable parent
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.396 D/GeckoViewMedia[C](23754): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.396 D/GeckoViewSettings[C](23754): receiveMessage: SettingsUpdate
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMedia"}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewAutofill[C](23754): onInit
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewModule( 3152): enableQueuing false
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewModule( 3152): dispatchQueued
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewMediaControl[C](23754): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.397 D/GeckoViewMediaControl[C](23754): onEnable
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.398 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.398 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewAutofill"}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.398 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMediaControl"}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.399 D/GeckoViewModule( 3152): enableQueuing false
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.399 D/GeckoViewModule( 3152): dispatchQueued
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.401 D/GeckoViewModule( 3152): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.401 D/GeckoViewNavigation( 3152): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.402 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.405 D/GeckoViewProgress( 3152): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.405 D/GeckoViewProgress( 3152): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.405 D/GeckoViewProgress( 3152): ProgressTracker start http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.405 D/GeckoViewProgress( 3152): ProgressTracker updateProgress
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.405 D/GeckoViewProgress( 3152): 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 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.406 D/GeckoViewProgress( 3152): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.407 D/GeckoSession( 3152): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.407 D/GeckoSession( 3152): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.413 I/art     (26818): Not late-enabling -Xcheck:jni (already on)
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.413 W/art     (26818): Unexpected CPU variant for X86 using defaults: x86_64
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.420 I/ActivityManager( 1616): Start proc 26818:org.mozilla.geckoview.test:tab11/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab11
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.421 I/ActivityManager( 1616): Process org.mozilla.geckoview.test:tab29 (pid 26774) has died
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.421 D/ActivityManager( 1616): cleanUpApplicationRecord -- 26774
[task 2021-06-30T07:52:25.125Z] 07:52:25     INFO -  06-30 08:52:24.422 D/ServiceAllocator( 3152): org.mozilla.gecko.process.GeckoChildProcessServices$tab11 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.434 I/MultiDex(26818): VM with version 2.1.0 has multidex support
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.434 I/MultiDex(26818): Installing application
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.434 I/MultiDex(26818): VM has multidex support, MultiDex support library is disabled.
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.435 I/ServiceChildProcess(26818): onCreate
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.436 D/GeckoThread(26818): State changed to LAUNCHED
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.437 I/GeckoThread(26818): preparing to run Gecko
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.440 D/ServiceAllocator( 3152): org.mozilla.gecko.process.GeckoChildProcessServices$tab11 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.441 D/GeckoViewXUL( 3152): WillChangeBrowserRemoteness
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.441 D/GeckoViewModule( 3152): unregisterListener
[task 2021-06-30T07:52:25.126Z] 07:52:25     INFO -  06-30 08:52:24.441 D/GeckoViewMediaControl( 3152): onDisable
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.441 D/GeckoViewModule( 3152): unregisterListener
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.441 D/GeckoViewMedia[C](23754): onDisable
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoViewMediaControl[C](23754): onDisable
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoViewSelectionActionDelegate[C](23754): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_CRASHREPORTER=1
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_CRASHREPORTER_SHUTDOWN=1
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoViewAutofill[C](23754): handleEvent: pagehide
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: XPCOM_DEBUG_BREAK=stack
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_DISABLE_NONLOCAL_CONNECTIONS=1
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_IN_AUTOMATION=1
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: R_LOG_VERBOSE=1
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: R_LOG_LEVEL=6
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: R_LOG_DESTINATION=stderr
[task 2021-06-30T07:52:25.127Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_WEBRENDER=1
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoThread(26818): env var: MOZ_FORCE_ENABLE_FISSION=1
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.442 D/GeckoViewAutofill(23754): Clearing auto-fill
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.443 D/GeckoViewXUL( 3152): DidChangeBrowserRemoteness
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoViewNavigation( 3152): sessionContextId=null
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoThread(26818): State changed to MOZGLUE_READY
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoViewModule( 3152): enableQueuing true
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoViewModule( 3152): registerListener ["GeckoView:MediaObserve","GeckoView:MediaUnobserve","GeckoView:MediaPlay","GeckoView:MediaPause","GeckoView:MediaSeek","GeckoView:MediaSetVolume","GeckoView:MediaSetMuted","GeckoView:MediaSetPlaybackRate"]
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoViewModule( 3152): enableQueuing true
[task 2021-06-30T07:52:25.128Z] 07:52:25     INFO -  06-30 08:52:24.444 D/GeckoViewMediaControl( 3152): onEnable
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.445 D/GeckoViewModule( 3152): 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 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.445 E/GeckoLibLoad(26818): Load sqlite start
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.446 E/GeckoLibLoad(26818): Load sqlite done
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.446 E/GeckoLibLoad(26818): Load nss start
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.446 E/GeckoLibLoad(26818): Load nss done
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.453 E/GeckoLibLoad(26818): Loaded libs in 7.541169ms total, 0ms(10ms) user, 0ms(0ms) system, 0(0) faults
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.453 D/GeckoThread(26818): State changed to LIBS_READY
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.453 W/GeckoThread(26818): zerdatime 513953 - runGecko
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.460 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.529 D/GeckoViewStartup(26818): observe: app-startup
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.532 D/GeckoViewConsole(26818): enabled = true
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.534 D/GeckoThread(26818): State changed to RUNNING
[task 2021-06-30T07:52:25.129Z] 07:52:25     INFO -  06-30 08:52:24.553 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.563 D/GeckoViewAutofill[C](26818): onInit
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewAutofill"}
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMedia"}
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewModule( 3152): enableQueuing false
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewModule( 3152): dispatchQueued
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.565 D/GeckoViewMedia[C](26818): onEnable
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.566 D/GeckoViewMediaControl[C](26818): onEnable
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.566 D/GeckoViewMediaControl[C](26818): onEnable
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.567 D/GeckoViewXUL( 3152): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMediaControl"}
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.567 D/GeckoViewModule( 3152): enableQueuing false
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.567 D/GeckoViewModule( 3152): dispatchQueued
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.572 D/GeckoViewMedia[C](26818): handleEvent: pagehide
[task 2021-06-30T07:52:25.130Z] 07:52:25     INFO -  06-30 08:52:24.575 D/GeckoViewSelectionActionDelegate[C](26818): handleEvent: visibilitychange
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.575 D/GeckoViewAutofill[C](26818): handleEvent: pagehide
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.577 D/GeckoViewAutofill(26818): Clearing auto-fill
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoViewNavigation( 3152): onLocationChange
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoSession( 3152): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoViewProgress( 3152): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoViewProgress( 3152): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoViewProgress( 3152): ProgressTracker changeLocation http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoViewProgress( 3152): onSecurityChange
[task 2021-06-30T07:52:25.131Z] 07:52:25     INFO -  06-30 08:52:24.582 D/GeckoSession( 3152): handleMessage GeckoView:SecurityChanged uri=null
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.584 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.616 D/GeckoViewContent( 3152): handleEvent: pagetitlechanged
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.618 D/GeckoViewContentDelegateChild[C](26818): handleEvent: DOMContentLoaded
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.619 D/GeckoViewProgressDelegate[C](26818): handleEvent: DOMContentLoaded
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.622 D/GeckoViewProgress( 3152): receiveMessage: DOMContentLoaded
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.622 D/GeckoViewProgress( 3152): ProgressTracker handleEvent: DOMContentLoaded
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.622 D/GeckoViewProgress( 3152): ProgressTracker updateProgress
[task 2021-06-30T07:52:25.132Z] 07:52:25     INFO -  06-30 08:52:24.622 D/GeckoViewProgress( 3152): ProgressTracker updateProgress data={"prev":15,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.622 D/GeckoSession( 3152): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.633 D/GeckoViewNavigation( 3152): onLocationChange
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.634 D/GeckoSession( 3152): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/selectionAction_frame.html
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.634 D/GeckoViewProgress( 3152): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/selectionAction_frame.html, flags=0
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.638 D/GeckoViewContentDelegateChild[C](26818): handleEvent: DOMContentLoaded
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.640 D/GeckoViewContent[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.640 D/GeckoViewAutofill[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.641 D/GeckoViewContentDelegateChild[C](26818): handleEvent: MozFirstContentfulPaint
[task 2021-06-30T07:52:25.133Z] 07:52:25     INFO -  06-30 08:52:24.652 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.668 D/GeckoViewNavigation( 3152): onLocationChange
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.669 D/GeckoViewProgress( 3152): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/selectionAction_frame.html, flags=0
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.669 D/GeckoViewContentDelegateChild[C](26818): handleEvent: DOMContentLoaded
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.669 D/GeckoSession( 3152): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/selectionAction_frame.html
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.671 D/GeckoViewContent[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.671 D/GeckoViewAutofill[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.679 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.690 D/GeckoViewProgressDelegate[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.134Z] 07:52:25     INFO -  06-30 08:52:24.690 D/GeckoViewContent[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.690 D/GeckoViewAutofill[C](26818): handleEvent: pageshow
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.691 D/WebExtension( 3152): handleMessage GeckoView:WebExtension:Connect
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.691 D/GeckoViewProgress( 3152): receiveMessage: pageshow
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.691 D/GeckoViewProgress( 3152): ProgressTracker handleEvent: pageshow
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.691 D/GeckoViewProgress( 3152): ProgressTracker updateProgress
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.691 D/GeckoViewProgress( 3152): ProgressTracker updateProgress data={"prev":55,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":true,"parsed":true} progress=100
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoViewProgress( 3152): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2021-06-30T07:52:25.135Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoViewProgress( 3152): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2021-06-30T07:52:25.136Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoViewProgress( 3152): ProgressTracker stop
[task 2021-06-30T07:52:25.136Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoSession( 3152): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-06-30T07:52:25.136Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoViewProgress( 3152): ProgressTracker updateProgress
[task 2021-06-30T07:52:25.136Z] 07:52:25     INFO -  06-30 08:52:24.692 D/GeckoViewProgress( 3152): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2021-06-30T07:52:25.136Z] 07:52:25     INFO -  06-30 08:52:24.693 D/GeckoSession( 3152): handleMessage GeckoView:PageStop uri=null
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.693 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.693 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"45ae1c84-7d1a-456e-9d17-fded65078f7d","eval":"document.querySelector('#contenteditable').textContent = ''"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.693 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.694 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.694 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.694 D/GeckoViewContentDelegateChild[C](26818): handleEvent: MozFirstContentfulPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.694 D/GeckoViewContentDelegateChild[C](26818): handleEvent: MozFirstContentfulPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.696 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"bf0662d0-442e-4e48-b39b-b12f06c43c22","eval":"document.querySelector('#contenteditable').focus()"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.701 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.709 D/GeckoViewAutofill[C](26818): handleEvent: focusin
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.712 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"0ccd562e-0778-4adc-a796-9f44cbddb4be","eval":"this['ade308a7-74a2-45b0-8a2c-f74dcdd2d06f'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('compositionupdate', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.715 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.715 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"59f5815b-9ddb-463f-823d-1601c47fc228","eval":"this['ade308a7-74a2-45b0-8a2c-f74dcdd2d06f']"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.716 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.718 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.719 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"39731b36-b74c-4a0e-bc94-695a1a62f9d9","eval":"new Promise(r => requestAnimationFrame(r))"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.719 W/GeckoEditable( 3152): Mismatched reply
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.735 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.735 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.736 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.736 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"10fdc418-9f1a-48bd-a99f-7113e2b75afb","args":{},"type":"GetRequestedLocales"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.739 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"2ebe6698-9b07-44cd-9a8f-8f94438e7d09","eval":"document.querySelector('#contenteditable').textContent"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.741 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1ca8a600-be1b-4550-a491-75edb6172a79","eval":"[\n                    document.getSelection().anchorOffset,\n                    document.getSelection().focusOffset]"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.745 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"5ef38f7d-749e-43bb-a220-f835263d6ab4","eval":"this['4f161d28-f400-4524-b70d-ebf09685033d'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('keyup', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.746 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.748 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.750 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"e94d5395-a4f1-4cdb-8161-7b92780b1a96","eval":"this['4f161d28-f400-4524-b70d-ebf09685033d']"}}
[task 2021-06-30T07:52:25.140Z] 07:52:25     INFO -  06-30 08:52:24.758 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"083f4ade-d79a-4bde-9cb8-e9d9745b5a3b","eval":"this['c369b7d7-9861-4d92-bbe6-12196664bb43'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('keyup', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.762 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"647b826c-91c5-407e-a23d-2ea739249272","eval":"this['c369b7d7-9861-4d92-bbe6-12196664bb43']"}}
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.768 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.772 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"84f44e73-5fe5-4981-a176-7670300facff","eval":"this['2807f2ca-0d27-4a3c-b392-66188a72abcd'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('keyup', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.774 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.775 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.776 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"afcf702f-1dd3-4b88-aba6-79ad060f7ae0","eval":"this['2807f2ca-0d27-4a3c-b392-66188a72abcd']"}}
[task 2021-06-30T07:52:25.141Z] 07:52:25     INFO -  06-30 08:52:24.779 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"461fe324-0edd-4f55-a27b-7798c8facef3","eval":"new Promise(r => requestAnimationFrame(r))"}}
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.785 E/eglCodecCommon( 3152): glUtilsParamSize: unknow param 0x00008caa
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.786 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"c9358529-69db-43a3-bf40-7a146a147e0a","args":{},"type":"GetRequestedLocales"}}
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.788 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1f7432f0-2301-454a-8861-76e814522614","eval":"this['27357c7d-aaaf-42fe-be20-066cc1ea845f'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('compositionupdate', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.793 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"e5aae5d6-2c62-4073-b369-688a2ab68896","eval":"this['27357c7d-aaaf-42fe-be20-066cc1ea845f']"}}
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.795 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.142Z] 07:52:25     INFO -  06-30 08:52:24.795 D/GeckoViewProgressDelegate[C](26818): handleEvent: MozAfterPaint
[task 2021-06-30T07:52:25.143Z] 07:52:25     INFO -  06-30 08:52:24.795 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"218690ac-1597-4838-9379-33ec1dcc2fef","eval":"this['edb75718-50a3-4ea7-a976-2704f76e77aa'] = new Promise(r => document.querySelector('#contenteditable').addEventListener('compositionend', r, { once: true })); true"}}
[task 2021-06-30T07:52:25.143Z] 07:52:25     INFO -  06-30 08:52:24.796 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.143Z] 07:52:25     INFO -  06-30 08:52:24.796 D/GeckoViewProgress( 3152): receiveMessage: MozAfterPaint
[task 2021-06-30T07:52:25.143Z] 07:52:25     INFO -  06-30 08:52:24.797 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"53430703-f83c-4fce-b846-d92b77a4ca02","eval":"this['edb75718-50a3-4ea7-a976-2704f76e77aa']"}}
[task 2021-06-30T07:52:25.143Z] 07:52:25     INFO -  06-30 08:52:24.798 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"f45a7a67-0a76-4d12-99a5-bcfebf8fe708","eval":"new Promise(r => requestAnimationFrame(r))"}}
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.803 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"8a687366-0b3d-4a51-8fbb-0f5d3568eb1e","args":{},"type":"GetRequestedLocales"}}
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.806 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"459483c6-4ed9-48d4-9b43-4c0a7215a8f7","eval":"document.querySelector('#contenteditable').textContent"}}
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.808 E/GeckoSessionTestRule( 3152): after evaluate
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.808 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"ba1d9945-b17c-4f91-85a4-e1fdfbf7d483","args":{"oldPrefs":{"dom.select_events.textcontrols.enabled":true}},"type":"RestorePrefs"}}
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.810 E/GeckoSessionTestRule( 3152): after performTestEndCheck
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.811 D/EGL_emulation( 3152): eglMakeCurrent: 0x7ef84774f9a0: ver 3 0 (tinfo 0x7ef847759d80)
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.812 D/GeckoViewContent( 3152): handleEvent: DOMWindowClose
[task 2021-06-30T07:52:25.144Z] 07:52:25     INFO -  06-30 08:52:24.812 D/GeckoViewConsole( 3152): onEvent GeckoView:WebExtension:List null
[task 2021-06-30T07:52:25.145Z] 07:52:25     INFO -  06-30 08:52:24.814 E/GeckoSessionTestRule( 3152): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 inputConnection_bug1593683[#contenteditable](org.mozilla.geckoview.test.TextInputDelegateTest)
[task 2021-06-30T07:52:25.145Z] 07:52:25  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.TextInputDelegateTest.inputConnection_bug1593683[#contenteditable] | java.lang.AssertionError: commit abc
[task 2021-06-30T07:52:25.145Z] 07:52:25     INFO -  TEST-INFO took 654ms

Comment 1

[Intermittent Failures Robot]

1 failures in 723 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
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1718734&startday=2021-06-28&endday=2021-07-04&tree=all

Comment 2

[[ex-Mozilla] Agi Sferro | :agi]

6c6f5da9-bf33-4082-a19d-7d99e8083d97 Mass rename intermittents due to Bug 1723034.

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1718734&startday=2021-08-09&endday=2021-08-15&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 2580 pushes (0.0 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-lite-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1718734&startday=2021-08-23&endday=2021-08-29&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1718734&startday=2021-08-30&endday=2021-09-05&tree=all