Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=504564592&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/COkCM4FfQ16jFqrpT4sYbg/runs/0/artifacts/public/logs/live_backing.log
[task 2025-04-18T10:16:37.034Z] 10:16:37 INFO - TEST-START | org.mozilla.geckoview.test.PromptDelegateTest#directoryTest
[task 2025-04-18T10:17:18.361Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1218
[task 2025-04-18T10:17:18.361Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2025-04-18T10:17:18.361Z] 10:17:18 INFO - org.mozilla.geckoview.test | Error in directoryTest(org.mozilla.geckoview.test.PromptDelegateTest):
[task 2025-04-18T10:17:18.361Z] 10:17:18 INFO - org.mozilla.geckoview.test | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1817)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1714)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1668)
[task 2025-04-18T10:17:18.362Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.PromptDelegateTest.directoryTest(PromptDelegateTest.kt:1090)
[task 2025-04-18T10:17:18.363Z] 10:17:18 INFO - org.mozilla.geckoview.test |
[task 2025-04-18T10:17:18.363Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2025-04-18T10:17:18.363Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=directoryTest
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PromptDelegateTest
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1817)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1714)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1668)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.PromptDelegateTest.directoryTest(PromptDelegateTest.kt:1090)
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test |
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=654
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2025-04-18T10:17:18.364Z] 10:17:18 INFO - Printing logcat for test:
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.376 E/GeckoSessionTestRule( 3449): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec directoryTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.376 E/GeckoSessionTestRule( 3449): before prepareStatement directoryTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.376 D/GeckoViewNavigation( 3449): onDisable
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.377 D/GeckoViewProcessHangMonitor( 3449): onDisable
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.377 D/GeckoViewModule( 3449): unregisterListener
[task 2025-04-18T10:17:18.980Z] 10:17:18 INFO - 04-18 10:16:36.378 D/GeckoViewProcessHangMonitor( 3449): onDestroy
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.378 D/GeckoViewSelectionActionDelegate[C](28606): handleEvent: visibilitychange
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.379 D/GeckoViewProgress( 3449): onDisable
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.379 D/GeckoViewModule( 3449): unregisterListener
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.380 D/GeckoViewSelectionAction( 3449): onDisable
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.380 D/GeckoViewModule( 3449): unregisterListener
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.381 D/GeckoViewModule( 3449): unregisterListener
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.382 D/GeckoViewMediaControl( 3449): onDisable
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.382 D/GeckoViewModule( 3449): unregisterListener
[task 2025-04-18T10:17:18.981Z] 10:17:18 INFO - 04-18 10:16:36.382 D/GeckoViewTranslations( 3449): onDisable
[task 2025-04-18T10:17:18.984Z] 10:17:18 INFO - 04-18 10:16:36.388 D/GeckoViewSessionStore( 3449): observe browsing-context-discarded
[task 2025-04-18T10:17:18.984Z] 10:17:18 INFO - 04-18 10:16:36.389 D/GeckoViewContentDelegateChild[C](28606): handleEvent: MozPaintStatusReset
[task 2025-04-18T10:17:18.984Z] 10:17:18 INFO - 04-18 10:16:36.391 D/GeckoViewSessionStore( 3449): observe browsing-context-discarded
[task 2025-04-18T10:17:18.984Z] 10:17:18 INFO - 04-18 10:16:36.392 D/GeckoViewSelectionActionDelegate[C](28606): handleEvent: visibilitychange
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.393 D/GeckoViewAutoFill[C](28606): handleEvent: pagehide
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.395 D/GeckoViewAutoFill[C](28606): handleEvent: focusout
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.396 D/EGL_emulation(26923): eglMakeCurrent: 0x7ad84cc505a0: ver 3 0 (tinfo 0x7ad84cc0c520)
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.396 D/GeckoViewAutoFill[C](28606): Auto-fill focus on null
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.396 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.405 I/Gecko (28606): [Child 28606, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSModuleLoader.cpp:672
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.406 I/Gecko (28606): [Child 28606, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/js/loader/ModuleLoaderBase.cpp:504
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.406 E/Isolated Web Content(28606): [JavaScript Error: "Error: Failed to load resource://gre/modules/PictureInPictureControls.sys.mjs" {file: "resource://gre/actors/PictureInPictureChild.sys.mjs" line: 466}]
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.406 E/Isolated Web Content(28606): get docState@resource://gre/actors/PictureInPictureChild.sys.mjs:466:9
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.406 E/Isolated Web Content(28606): stopTrackingMouseOverVideos@resource://gre/actors/PictureInPictureChild.sys.mjs:932:17
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.406 E/Isolated Web Content(28606): didDestroy@resource://gre/actors/PictureInPictureChild.sys.mjs:347:10
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.425 D/GeckoViewStartup( 3449): onEvent GeckoView:StorageDelegate:Attached
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.425 I/Gecko ( 3449): nsWindow[0x7ad803ca2a00]::Create 0x0 [0 0 1 1]
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.438 E/GeckoConsole( 3449): [JavaScript Error: "TypeError: can't access property "WindowEventDispatcher", win is null" {file: "resource://gre/modules/GeckoViewSessionStore.sys.mjs" line: 148}]
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.438 E/GeckoConsole( 3449): onTabStateUpdate@resource://gre/modules/GeckoViewSessionStore.sys.mjs:148:5
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.438 E/GeckoConsole( 3449): updateSessionStoreFromTabListener@resource://gre/modules/GeckoViewSessionStore.sys.mjs:242:10
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.438 E/GeckoConsole( 3449): SSF_updateSessionStore@resource://gre/modules/SessionStoreFunctions.sys.mjs:46:27
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.438 E/GeckoConsole( 3449): UpdateSessionStore@resource://gre/modules/SessionStoreFunctions.sys.mjs:16:46
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.442 D/GeckoViewContentDelegateParent( 3449): receiveMessage: DispatcherMessage
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.442 D/GeckoViewActorParent( 3449): receiveMessage window destroyed DispatcherMessage GeckoView:PaintStatusReset
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.447 D/GeckoViewAutoFill( 3449): receiveMessage Clear
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.449 D/GeckoViewAutoFill( 3449): receiveMessage Focus
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.450 W/GeckoViewAutofill( 3449): Disregarding old session 3ff79154-ca68-4868-9e3e-ccfb233b4a0d
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.459 I/Gecko ( 3449): nsWindow[0x7ad803ca2a00]::Resize [0.000000 0.000000 100.000000 100.000000] (repaint 0)
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.459 I/Gecko ( 3449): nsWindow: 0x7ad803ca2a00 OnSizeChanged [100 100]
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.462 I/ServiceChildProcess(28606): Destroying GeckoServiceChildProcess
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.465 I/art (28606): System.exit called, status: 0
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.465 I/AndroidRuntime(28606): VM exiting with result code 0, cleanup skipped.
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.475 I/Gecko ( 3449): [Parent 3449, IPC I/O Parent] WARNING: [1.1]: Dropping message '<null>'; no connection to unknown peer 47AE96FBF9914CDA.597FC0B4EFB77B69: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.475 I/ActivityManager( 1658): Process org.mozilla.geckoview.test:tab30 (pid 28606) has died
[task 2025-04-18T10:17:18.985Z] 10:17:18 INFO - 04-18 10:16:36.476 D/ActivityManager( 1658): cleanUpApplicationRecord -- 28606
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.476 I/Gecko ( 3449): [Parent 3449, IPC I/O Parent] WARNING: [1.1]: Rejecting introduction request from 'C6A832220F4304FE.56384C3B4DDF34A8' for unknown peer '47AE96FBF9914CDA.597FC0B4EFB77B69': file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:699
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.476 I/Gecko (26923): [GPU 26923, IPC I/O Child] WARNING: [C6A832220F4304FE.56384C3B4DDF34A8]: Could not be introduced to peer 47AE96FBF9914CDA.597FC0B4EFB77B69: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:628
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.476 E/KernelCpuSpeedReader( 1658): Failed to read cpu-freq: /sys/devices/system/cpu/cpu0/cpufreq/stats/time_in_state (No such file or directory)
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): Failed to read uid_cputime: /proc/uid_cputime/show_uid_stat (No such file or directory)
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): failed to remove uid from uid_cputime module
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): java.io.FileNotFoundException: /proc/uid_cputime/remove_uid_range (No such file or directory)
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at java.io.FileOutputStream.open(Native Method)
[task 2025-04-18T10:17:18.986Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at java.io.FileOutputStream.<init>(FileOutputStream.java:221)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at java.io.FileOutputStream.<init>(FileOutputStream.java:108)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at java.io.FileWriter.<init>(FileWriter.java:63)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at com.android.internal.os.KernelUidCpuTimeReader.removeUid(KernelUidCpuTimeReader.java:158)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at com.android.internal.os.BatteryStatsImpl.removeIsolatedUidLocked(BatteryStatsImpl.java:2943)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at com.android.server.am.BatteryStatsService$BatteryStatsHandler.handleMessage(BatteryStatsService.java:141)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at android.os.Handler.dispatchMessage(Handler.java:102)
[task 2025-04-18T10:17:18.987Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at android.os.Looper.loop(Looper.java:154)
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at android.os.HandlerThread.run(HandlerThread.java:61)
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.477 E/KernelUidCpuTimeReader( 1658): at com.android.server.ServiceThread.run(ServiceThread.java:46)
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.499 D/GeckoViewNavigation( 3449): sessionContextId=null
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.504 D/GeckoViewSessionStore( 3449): observe browsing-context-did-set-embedder
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.505 D/GeckoViewSessionStore( 3449): observe browsing-context-did-set-embedder
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.515 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.592 W/webrender::device::gl(26923): Missing optimized shader source for gpu_cache_update
[task 2025-04-18T10:17:18.988Z] 10:17:18 INFO - 04-18 10:16:36.600 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008741
[task 2025-04-18T10:17:18.989Z] 10:17:18 INFO - 04-18 10:16:36.615 D/GeckoViewModule( 3449): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:HasCookieBannerRuleForBrowsingContextTree","GeckoView:RestoreState","GeckoView:ContainsFormData","GeckoView:RequestCreateAnalysis","GeckoView:RequestAnalysisStatus","GeckoView:RequestAnalysisCreationStatus","GeckoView:PollForAnalysisCompleted","GeckoView:SendClickAttributionEvent","GeckoView:SendImpressionAttributionEvent","GeckoView:SendPlacementAttributionEvent","GeckoView:RequestAnalysis","GeckoView:RequestRecommendations","GeckoView:ReportBackInStock","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:SetPriorityHint","GeckoView:UpdateInitData","GeckoView:ZoomToInput","GeckoView:IsPdfJs","GeckoView:GetWebCompatInfo","GeckoView:SendMoreWebCompatInfo"]
[task 2025-04-18T10:17:18.989Z] 10:17:18 INFO - 04-18 10:16:36.616 D/EGL_emulation(26923): eglMakeCurrent: 0x7ad84cc505a0: ver 3 0 (tinfo 0x7ad84cc0c520)
[task 2025-04-18T10:17:18.989Z] 10:17:18 INFO - 04-18 10:16:36.616 D/GeckoViewNavigation( 3449): onInit
[task 2025-04-18T10:17:18.989Z] 10:17:18 INFO - 04-18 10:16:36.616 D/GeckoViewModule( 3449): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory","GeckoView:DotPrintFinish"]
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.616 D/GeckoViewNavigation( 3449): onEnable
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.617 D/GeckoViewProcessHangMonitor( 3449): onInit
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.617 D/GeckoViewProcessHangMonitor( 3449): onEnable
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.618 D/GeckoViewModule( 3449): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.619 D/GeckoViewProgress( 3449): onEnable
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.620 D/GeckoSession( 3449): handleMessage GeckoView:PageStart uri=about:blank
[task 2025-04-18T10:17:18.990Z] 10:17:18 INFO - 04-18 10:16:36.620 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$SelectionActionDelegate.onDismissClipboardPermissionRequest(org.mozilla.geckoview.GeckoSession)
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 D/GeckoSession( 3449): handleMessage GeckoView:LocationChange uri=about:blank
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List,java.lang.Boolean)
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2025-04-18T10:17:18.991Z] 10:17:18 INFO - 04-18 10:16:36.621 D/GeckoSession( 3449): handleMessage GeckoView:PageStop uri=null
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.621 E/GeckoSessionTestRule( 3449): after prepareStatement
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.622 D/GeckoViewModule( 3449): registerListener GeckoView:FlushSessionState
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.624 D/GeckoViewSelectionAction( 3449): onEnable
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.625 D/GeckoViewModule( 3449): registerListener ["GeckoView:ExecuteSelectionAction"]
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.626 D/GeckoViewSettings( 3449): onInit
[task 2025-04-18T10:17:18.992Z] 10:17:18 INFO - 04-18 10:16:36.626 D/GeckoViewModule( 3449): registerListener ["GeckoView:GetUserAgent"]
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.626 D/GeckoViewSettings( 3449): onSettingsUpdate: {"isExtensionPopup":false,"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}
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.628 D/GeckoViewModule( 3449): registerListener ["GeckoView:WebExtension:SetTabActive","GeckoView:FlushSessionState"]
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.629 D/GeckoViewModule( 3449): registerListener ["ContentBlocking:RequestLog"]
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.630 D/GeckoViewMediaControl( 3449): onInitBrowser
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.631 D/GeckoViewMediaControl( 3449): onEnable
[task 2025-04-18T10:17:18.993Z] 10:17:18 INFO - 04-18 10:16:36.632 D/GeckoViewModule( 3449): 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 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.633 D/GeckoViewTranslations( 3449): onInit
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.633 D/GeckoViewSelectionActionDelegate[C](17299): handleEvent: visibilitychange
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.633 D/GeckoViewModule( 3449): registerListener ["GeckoView:Translations:Translate","GeckoView:Translations:RestorePage","GeckoView:Translations:GetNeverTranslateSite","GeckoView:Translations:SetNeverTranslateSite"]
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.633 D/GeckoViewTranslations( 3449): onEnable
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.639 D/GeckoViewSettings[C](17299): receiveMessage: SettingsUpdate
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.641 D/GeckoViewContent( 3449): observe: ipc:content-shutdown
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.647 I/GeckoSession( 3449): zerdatime 1421486 - chrome startup finished
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.648 I/Gecko ( 3449): nsWindow[0x7ad803ca2a00]::Show 1
[task 2025-04-18T10:17:18.994Z] 10:17:18 INFO - 04-18 10:16:36.652 I/Gecko ( 3449): nsWindow[0x7ad803ca2a00]::Resize [0.000000 0.000000 100.000000 100.000000] (repaint 0)
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.654 W/GeckoEditableChild(17299): No editable parent
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.657 I/Gecko ( 3449): nsWindow 0x7ad7fa2b9b00 destructor
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.737 D/GeckoViewConsole( 3449): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"bb19c80c-0f95-468d-8af6-dc7d977ed67a","args":{"oldPrefs":{},"newPrefs":{"dom.webkitBlink.dirPicker.enabled":true,"dom.disable_open_during_load":false}},"type":"SetPrefs"}}
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.742 D/GeckoViewXUL( 3449): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.743 D/GeckoViewXUL( 3449): onEvent GeckoView:UpdateModuleState {"enabled":true,"module":"GeckoViewExperiment"}
[task 2025-04-18T10:17:18.995Z] 10:17:18 INFO - 04-18 10:16:36.760 D/GeckoViewModule( 3449): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/prompts.html","flags":0,"textDirectiveUserActivation":false,"headerFilter":1}
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.760 D/GeckoViewNavigation( 3449): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/prompts.html","flags":0,"textDirectiveUserActivation":false,"headerFilter":1}
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.766 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.774 D/GeckoViewProgress( 3449): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.775 D/GeckoViewProgress( 3449): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.775 D/GeckoViewProgress( 3449): ProgressTracker start http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.775 D/GeckoViewProgress( 3449): ProgressTracker updateProgress
[task 2025-04-18T10:17:18.996Z] 10:17:18 INFO - 04-18 10:16:36.776 D/GeckoViewProgress( 3449): ProgressTracker updateProgress data={"prev":0,"uri":"http://localhost:4245/assets/www/prompts.html","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.776 D/GeckoSession( 3449): handleMessage GeckoView:ProgressChanged uri=null
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.777 D/GeckoViewProgress( 3449): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.777 D/GeckoSession( 3449): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.786 I/Gecko ( 3449): [Parent 3449, Main Thread] WARNING: BounceTrackingState::OnDocumentStartRequest failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/netwerk/ipc/DocumentLoadListener.cpp:2747
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.798 I/art (28650): Late-enabling -Xcheck:jni
[task 2025-04-18T10:17:18.997Z] 10:17:18 INFO - 04-18 10:16:36.798 W/art (28650): Unexpected CPU variant for X86 using defaults: x86_64
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.803 I/ActivityManager( 1658): Start proc 28650:org.mozilla.geckoview.test:tab39/u0i550 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab39
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.805 D/ServiceAllocator( 3449): org.mozilla.gecko.process.GeckoChildProcessServices$tab39 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.830 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.832 I/ServiceChildProcess(28650): onCreate
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.832 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.837 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:18.998Z] 10:17:18 INFO - 04-18 10:16:36.848 D/GeckoThread(28650): State changed to LAUNCHED
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.849 I/GeckoThread(28650): preparing to run Gecko
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_CRASHREPORTER=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_CRASHREPORTER_SHUTDOWN=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: XPCOM_DEBUG_BREAK=stack
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_DISABLE_NONLOCAL_CONNECTIONS=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_IN_AUTOMATION=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: R_LOG_VERBOSE=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: R_LOG_LEVEL=6
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: R_LOG_DESTINATION=stderr
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_WEBRENDER=1
[task 2025-04-18T10:17:18.999Z] 10:17:18 INFO - 04-18 10:16:36.858 D/GeckoThread(28650): env var: MOZ_FORCE_ENABLE_FISSION=1
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.859 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libmozglue.so: unused DT entry: type 0x24 arg 0x3ded0
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.859 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libmozglue.so: unused DT entry: type 0x23 arg 0x200
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.859 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libmozglue.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.862 D/GeckoThread(28650): State changed to MOZGLUE_READY
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.863 W/ContextImpl(28650): Failed to ensure /data/user/0/org.mozilla.geckoview.test/files: mkdir failed: EACCES (Permission denied)
[task 2025-04-18T10:17:19.000Z] 10:17:19 INFO - 04-18 10:16:36.865 W/ContextImpl(28650): Failed to ensure /data/user/0/org.mozilla.geckoview.test/cache: mkdir failed: EACCES (Permission denied)
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.865 D/GeckoLoader(28650): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.865 D/GeckoLoader(28650): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.866 E/GeckoLibLoad(28650): Load sqlite start
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.866 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libnss3.so: unused DT entry: type 0x24 arg 0x163f0
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.866 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libnss3.so: unused DT entry: type 0x23 arg 0x698
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.867 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libnss3.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.867 E/GeckoLibLoad(28650): Load sqlite done
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.867 D/GeckoLoader(28650): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2025-04-18T10:17:19.001Z] 10:17:19 INFO - 04-18 10:16:36.867 E/GeckoLibLoad(28650): Load nss start
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 E/GeckoLibLoad(28650): Load nss done
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 D/GeckoLoader(28650): Library base=/data/app/org.mozilla.geckoview.test-1/lib/x86_64
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libxul.so: unused DT entry: type 0x24 arg 0x5f0f8
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libxul.so: unused DT entry: type 0x23 arg 0x1d670
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libxul.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.002Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libgkcodecs.so: unused DT entry: type 0x24 arg 0x34d0
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libgkcodecs.so: unused DT entry: type 0x23 arg 0xa60
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.868 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libgkcodecs.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.876 E/GeckoLibLoad(28650): Loaded libs in 8.175740ms total, 10ms(0ms) user, 0ms(0ms) system, 0(0) faults
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.876 D/GeckoThread(28650): State changed to LIBS_READY
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.876 W/GeckoThread(28650): zerdatime 1421716 - runGecko
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.901 I/Gecko ( 3449): [Parent 3449, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040154 (NS_ERROR_FACTORY_NOT_REGISTERED): file /builds/worker/checkouts/gecko/extensions/spellcheck/src/mozSpellChecker.cpp:364
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.904 D/ServiceAllocator( 3449): org.mozilla.gecko.process.GeckoChildProcessServices$tab39 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2025-04-18T10:17:19.003Z] 10:17:19 INFO - 04-18 10:16:36.913 D/GeckoViewXUL( 3449): WillChangeBrowserRemoteness
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.913 D/GeckoViewMediaControl( 3449): onDestroyBrowser
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.917 D/GeckoViewSessionStore( 3449): observe browsing-context-did-set-embedder
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.917 D/GeckoViewSelectionActionDelegate[C](17299): handleEvent: visibilitychange
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.919 D/GeckoViewSessionStore( 3449): observe browsing-context-did-set-embedder
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.919 D/GeckoViewAutoFill[C](17299): handleEvent: pagehide
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.923 D/GeckoViewXUL( 3449): DidChangeBrowserRemoteness
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.923 D/GeckoViewNavigation( 3449): sessionContextId=null
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.924 D/GeckoViewMediaControl( 3449): onInitBrowser
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.930 D/GeckoViewAutoFill( 3449): receiveMessage Clear
[task 2025-04-18T10:17:19.004Z] 10:17:19 INFO - 04-18 10:16:36.932 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:36.945 I/Gecko (28650): [Child 28650, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:472
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:36.950 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:36.952 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:36.958 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:36.984 D/GeckoThread(28650): State changed to RUNNING
[task 2025-04-18T10:17:19.005Z] 10:17:19 INFO - 04-18 10:16:37.020 D/GeckoViewStartup(28650): observe: content-process-ready-for-script
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.024 D/GeckoViewConsole(28650): enabled = true
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.098 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.102 D/GeckoViewXUL( 3449): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.113 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.116 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.121 W/webrender::device::gl(26923): Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.122 D/GeckoViewSelectionActionDelegate[C](28650): handleEvent: visibilitychange
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.127 D/GeckoViewAutoFill[C](28650): handleEvent: pagehide
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.167 D/GeckoViewAutoFill( 3449): receiveMessage Clear
[task 2025-04-18T10:17:19.006Z] 10:17:19 INFO - 04-18 10:16:37.176 D/GeckoViewNavigation( 3449): onLocationChange
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.178 D/GeckoViewTranslations( 3449): handleEvent: TranslationsParent:LanguageState
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.178 D/GeckoViewProgress( 3449): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/prompts.html, flags=0
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.179 D/GeckoSession( 3449): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.179 D/GeckoViewProgress( 3449): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/prompts.html, flags=0
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.179 D/GeckoViewProgress( 3449): ProgressTracker changeLocation http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.179 D/GeckoViewProgress( 3449): onSecurityChange
[task 2025-04-18T10:17:19.007Z] 10:17:19 INFO - 04-18 10:16:37.180 D/GeckoSession( 3449): handleMessage GeckoView:SecurityChanged uri=null
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.351 D/WebExtension( 3449): handleMessage GeckoView:WebExtension:Connect
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.369 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libsoftokn3.so: unused DT entry: type 0x24 arg 0x2698
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.369 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libsoftokn3.so: unused DT entry: type 0x23 arg 0x68
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.369 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libsoftokn3.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.370 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libfreebl3.so: unused DT entry: type 0x24 arg 0xec8
[task 2025-04-18T10:17:19.008Z] 10:17:19 INFO - 04-18 10:16:37.370 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libfreebl3.so: unused DT entry: type 0x23 arg 0x68
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.371 W/linker (28650): /data/app/org.mozilla.geckoview.test-1/lib/x86_64/libfreebl3.so: unused DT entry: type 0x25 arg 0x8
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.402 I/Gecko (28650): [Child 28650, Main Thread] WARNING: 'ps->NeedStyleFlush()', file /builds/worker/checkouts/gecko/dom/base/Document.cpp:18209
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.428 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.431 D/GeckoViewContent( 3449): handleEvent: pagetitlechanged
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.433 D/GeckoViewContentDelegateChild[C](28650): handleEvent: DOMContentLoaded
[task 2025-04-18T10:17:19.009Z] 10:17:19 INFO - 04-18 10:16:37.445 I/Gecko ( 3449): [Parent 3449, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/cookiebanners/nsCookieBannerService.cpp:788
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.445 I/Gecko ( 3449): console.error: "Fail on getting domain pref:" [Exception... "Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsICookieBannerService.getDomainPref]" nsresult: "0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS)" location: "JS frame :: resource://gre/actors/CookieBannerParent.sys.mjs :: receiveMessage :: line 182" data: no]
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.446 E/GeckoConsole( 3449): [JavaScript Error: "Fail on getting domain pref: [Exception... "Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsICookieBannerService.getDomainPref]" nsresult: "0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS)" location: "JS frame :: resource://gre/actors/CookieBannerParent.sys.mjs :: receiveMessage :: line 182" data: no]"]
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.446 D/GeckoViewProgressDelegate[C](28650): handleEvent: DOMContentLoaded
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.449 D/GeckoViewProgress( 3449): receiveMessage: DOMContentLoaded
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.449 D/GeckoViewProgress( 3449): ProgressTracker handleEvent: DOMContentLoaded
[task 2025-04-18T10:17:19.010Z] 10:17:19 INFO - 04-18 10:16:37.449 D/GeckoViewProgress( 3449): ProgressTracker updateProgress
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.449 D/GeckoViewProgress( 3449): ProgressTracker updateProgress data={"prev":15,"uri":"http://localhost:4245/assets/www/prompts.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.450 D/GeckoSession( 3449): handleMessage GeckoView:ProgressChanged uri=null
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.454 D/GeckoViewContent[C](28650): handleEvent: pageshow
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.455 D/GeckoViewProgressDelegate[C](28650): handleEvent: pageshow
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.456 D/GeckoViewProgress( 3449): receiveMessage: pageshow
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.456 D/GeckoViewProgress( 3449): ProgressTracker handleEvent: pageshow
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.456 D/GeckoViewAutoFill[C](28650): handleEvent: pageshow
[task 2025-04-18T10:17:19.011Z] 10:17:19 INFO - 04-18 10:16:37.456 D/GeckoViewProgress( 3449): ProgressTracker updateProgress
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.457 D/GeckoViewProgress( 3449): ProgressTracker updateProgress data={"prev":55,"uri":"http://localhost:4245/assets/www/prompts.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":true,"parsed":true} progress=100
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.457 D/GeckoSession( 3449): handleMessage GeckoView:ProgressChanged uri=null
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.462 D/GeckoViewProgress( 3449): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.462 D/GeckoViewProgress( 3449): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/prompts.html
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.463 D/GeckoViewProgress( 3449): ProgressTracker stop
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.463 D/GeckoViewProgress( 3449): ProgressTracker updateProgress
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.464 D/GeckoViewProgress( 3449): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2025-04-18T10:17:19.012Z] 10:17:19 INFO - 04-18 10:16:37.464 D/GeckoSession( 3449): handleMessage GeckoView:PageStop uri=null
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.465 D/GeckoViewContentDelegateChild[C](28650): handleEvent: MozFirstContentfulPaint
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.467 D/GeckoViewContentDelegateParent( 3449): receiveMessage: DispatcherMessage
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.468 D/GeckoViewConsole( 3449): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"76f3efab-b460-4e82-a5a2-ab7a0e3cb95b","eval":"document.addEventListener('click', () => document.getElementById('direxample').click(), { once: true });"}}
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.471 D/GeckoViewContentDelegateChild[C](28650): handleEvent: MozFirstContentfulPaint
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.473 D/GeckoViewContentDelegateParent( 3449): receiveMessage: DispatcherMessage
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.503 D/GeckoViewContentDelegateParent( 3449): receiveMessage: DispatcherMessage
[task 2025-04-18T10:17:19.013Z] 10:17:19 INFO - 04-18 10:16:37.539 I/Gecko ( 3449): [Parent 3449, Main Thread] WARNING: NS_ENSURE_TRUE(record) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:296
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.539 I/Gecko ( 3449): [Parent 3449, Main Thread] WARNING: Running RecordStatefulBounces after a timeout failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:746
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.625 D/GeckoViewProgressDelegate[C](28650): handleEvent: MozAfterPaint
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.627 D/GeckoViewProgressDelegate[C](28650): handleEvent: MozAfterPaint
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.647 D/GeckoViewProgress( 3449): receiveMessage: MozAfterPaint
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.648 D/GeckoViewProgress( 3449): receiveMessage: MozAfterPaint
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.864 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:37.898 D/GeckoViewProgressDelegate[C](28650): handleEvent: MozAfterPaint
[task 2025-04-18T10:17:19.014Z] 10:17:19 INFO - 04-18 10:16:42.929 D/GeckoViewProgress( 3449): receiveMessage: MozAfterPaint
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): Get idle time: time since reset 5449 msec
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): Idle timer callback: current idle time 5449 msec
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): next timeout 4294967289551 msec from now
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): SetTimerExpiryIfBefore: next timeout 4294967289551 msec from now
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): reset timer expiry to 4294967289561 msec from now
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.931 D/GeckoIdleService( 3449): Idle timer callback: tell observer 0x7ad8217c30a8 user is idle
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.933 D/ServiceAllocator( 3449): org.mozilla.gecko.process.GeckoChildProcessServices$tab27 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2025-04-18T10:17:19.015Z] 10:17:19 INFO - 04-18 10:16:42.940 D/GeckoViewPrompt[C](28650): handleEvent: mozshowdropdown-sourcetouch
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:42.963 D/GeckoSession( 3449): handleMessage GeckoView:Prompt
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:42.963 D/Prompts ( 3449): handleEvent choice
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:42.998 D/GeckoViewAutocomplete( 3449): fetchAddresses
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:43.000 D/GeckoViewAutocomplete( 3449): fetchCreditCards
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:43.132 D/GeckoViewAutoFill[C](28650): handleEvent: focusin
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:43.134 W/Isolated Web Content(28650): [JavaScript Warning: "<input> picker was blocked due to lack of user activation." {file: "eval" line: 1}]
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:43.135 D/GeckoViewPrompt[C](28650): handleEvent: click
[task 2025-04-18T10:17:19.016Z] 10:17:19 INFO - 04-18 10:16:43.147 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:43.238 E/eglCodecCommon(26923): glUtilsParamSize: unknow param 0x00008caa
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:43.240 D/GeckoViewProgressDelegate[C](28650): handleEvent: MozAfterPaint
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:43.242 D/GeckoViewProgress( 3449): receiveMessage: MozAfterPaint
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:43.320 D/GeckoViewProgressDelegate[C](28650): handleEvent: MozAfterPaint
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:43.322 D/GeckoViewProgress( 3449): receiveMessage: MozAfterPaint
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:47.658 D/GeckoSession( 3449): handleMessage GeckoView:StateUpdated uri=null
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:16:47.659 W/GeckoSession( 3449): No history entries found.
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): Error
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2025-04-18T10:17:19.017Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutRunnable.run(UiThreadUtils.java:49)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.os.Handler.handleCallback(Handler.java:751)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:158)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1817)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1714)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitUntilCalled(GeckoSessionTestRule.java:1668)
[task 2025-04-18T10:17:19.018Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.PromptDelegateTest.directoryTest(PromptDelegateTest.kt:1090)
[task 2025-04-18T10:17:19.019Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at java.lang.reflect.Method.invoke(Native Method)
[task 2025-04-18T10:17:19.019Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
[task 2025-04-18T10:17:19.019Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2025-04-18T10:17:19.019Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
[task 2025-04-18T10:17:19.019Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.junit.rules.Verifier$1.evaluate(Verifier.java:35)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0(GeckoSessionTestRule.java:1502)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.$r8$lambda$iCBopfpsbLU-HFWW53O-4m1PB6c(GeckoSessionTestRule.java)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2$$ExternalSyntheticLambda1.run(D8$$SyntheticClass:0)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:428)
[task 2025-04-18T10:17:19.020Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at java.util.concurrent.FutureTask.run(FutureTask.java:237)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.os.Handler.handleCallback(Handler.java:751)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.os.Looper.loop(Looper.java:154)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at java.lang.reflect.Method.invoke(Native Method)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.661 E/GeckoSessionTestRule( 3449): at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2025-04-18T10:17:19.021Z] 10:17:19 INFO - 04-18 10:17:17.665 D/GeckoViewConsole( 3449): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"316d2b23-8da9-4810-b0c6-4327abdb8fb2","args":{"oldPrefs":{"dom.webkitBlink.dirPicker.enabled":false,"dom.disable_open_during_load":false}},"type":"RestorePrefs"}}
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - 04-18 10:17:17.680 D/EGL_emulation(26923): eglMakeCurrent: 0x7ad84cc505a0: ver 3 0 (tinfo 0x7ad84cc0c520)
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - 04-18 10:17:17.689 D/GeckoViewContent( 3449): handleEvent: DOMWindowClose
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - 04-18 10:17:17.694 D/GeckoViewConsole( 3449): onEvent GeckoView:WebExtension:List null
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - 04-18 10:17:17.700 I/Gecko ( 3449): nsWindow[0x7ad803ca2a00]::Show 0
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - 04-18 10:17:17.700 E/GeckoSessionTestRule( 3449): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 directoryTest(org.mozilla.geckoview.test.PromptDelegateTest)
[task 2025-04-18T10:17:19.022Z] 10:17:19 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.PromptDelegateTest#directoryTest | org.mozilla.geckoview.test.util.UiThreadUtils$TimeoutException: Timed out after 30000ms
[task 2025-04-18T10:17:19.022Z] 10:17:19 INFO - TEST-INFO took 41946ms
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=1218
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=invalidShareUrlReturnsTypeError
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.PromptDelegateTest
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=655
[task 2025-04-18T10:17:19.023Z] 10:17:19 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: 1
Description
•