Closed Bug 1774319 Opened 3 years ago Closed 3 years ago

Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#cut[#designmode] | java.lang.RuntimeException: android.os.DeadSystemException

Categories

(GeckoView :: General, defect, P5)

Unspecified
All
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2022-06-15T01:33:35.260Z] 01:33:35     INFO -  TEST-START | org.mozilla.geckoview.test.SelectionActionDelegateTest#cut[#designmode]
[task 2022-06-15T01:33:39.677Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=925
[task 2022-06-15T01:33:39.677Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2022-06-15T01:33:39.678Z] 01:33:39     INFO -  org.mozilla.geckoview.test | Process crashed while executing cut[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest):
[task 2022-06-15T01:33:39.678Z] 01:33:39     INFO -  org.mozilla.geckoview.test | java.lang.RuntimeException: android.os.DeadSystemException
[task 2022-06-15T01:33:39.678Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.handlePauseActivity(ActivityThread.java:3648)
[task 2022-06-15T01:33:39.679Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.-wrap16(ActivityThread.java)
[task 2022-06-15T01:33:39.679Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1481)
[task 2022-06-15T01:33:39.679Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:102)
[task 2022-06-15T01:33:39.680Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2022-06-15T01:33:39.680Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-06-15T01:33:39.680Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-06-15T01:33:39.681Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-06-15T01:33:39.681Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-06-15T01:33:39.681Z] 01:33:39     INFO -  org.mozilla.geckoview.test | Caused by: android.os.DeadSystemException
[task 2022-06-15T01:33:39.682Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	... 9 more
[task 2022-06-15T01:33:39.682Z] 01:33:39     INFO -  org.mozilla.geckoview.test |
[task 2022-06-15T01:33:39.682Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2022-06-15T01:33:39.682Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=cut[#designmode]
[task 2022-06-15T01:33:39.683Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.SelectionActionDelegateTest
[task 2022-06-15T01:33:39.683Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=java.lang.RuntimeException: android.os.DeadSystemException
[task 2022-06-15T01:33:39.683Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.handlePauseActivity(ActivityThread.java:3648)
[task 2022-06-15T01:33:39.684Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.-wrap16(ActivityThread.java)
[task 2022-06-15T01:33:39.684Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1481)
[task 2022-06-15T01:33:39.684Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Handler.dispatchMessage(Handler.java:102)
[task 2022-06-15T01:33:39.684Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.os.Looper.loop(Looper.java:154)
[task 2022-06-15T01:33:39.685Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2022-06-15T01:33:39.685Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at java.lang.reflect.Method.invoke(Native Method)
[task 2022-06-15T01:33:39.685Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2022-06-15T01:33:39.686Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2022-06-15T01:33:39.686Z] 01:33:39     INFO -  org.mozilla.geckoview.test | Caused by: android.os.DeadSystemException
[task 2022-06-15T01:33:39.686Z] 01:33:39     INFO -  org.mozilla.geckoview.test | 	... 9 more
[task 2022-06-15T01:33:39.687Z] 01:33:39     INFO -  org.mozilla.geckoview.test |
[task 2022-06-15T01:33:39.687Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=646
[task 2022-06-15T01:33:39.687Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2022-06-15T01:33:39.688Z] 01:33:39     INFO -  Printing logcat for test:
[task 2022-06-15T01:33:39.746Z] 01:33:39     INFO -  06-15 01:33:34.967 E/GeckoSessionTestRule( 3409): test_start 1f0befec-3ff2-40ff-89cf-b127eb38b1ec cut[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-15T01:33:39.746Z] 01:33:39     INFO -  06-15 01:33:34.967 E/GeckoSessionTestRule( 3409): before prepareStatement cut[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-15T01:33:39.746Z] 01:33:39     INFO -  06-15 01:33:34.969 D/GeckoViewStartup( 3409): onEvent GeckoView:StorageDelegate:Attached
[task 2022-06-15T01:33:39.747Z] 01:33:39     INFO -  06-15 01:33:34.977 D/EGL_emulation( 3409): eglMakeCurrent: 0x7c0c7b0dbd80: ver 3 0 (tinfo 0x7c0c9e474c60)
[task 2022-06-15T01:33:39.748Z] 01:33:39     INFO -  06-15 01:33:34.979 I/WindowManager( 1660): Destroying surface Surface(name=Starting org.mozilla.geckoview.test) called by com.android.server.wm.WindowStateAnimator.destroySurface:2014 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:881 com.android.server.wm.WindowState.removeLocked:1449 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2478 com.android.server.wm.WindowManagerService.removeWindowLocked:2436 com.android.server.wm.WindowManagerService.removeWindowLocked:2305 com.android.server.wm.WindowManagerService.removeWindow:2300 com.android.server.wm.Session.remove:193
[task 2022-06-15T01:33:39.748Z] 01:33:39     INFO -  06-15 01:33:34.984 D/EGL_emulation( 2147): eglMakeCurrent: 0x7c0c9e43a5a0: ver 3 0 (tinfo 0x7c0c9e40c8c0)
[task 2022-06-15T01:33:39.748Z] 01:33:39     INFO -  06-15 01:33:34.987 D/GeckoViewNavigation( 3409): sessionContextId=null
[task 2022-06-15T01:33:39.749Z] 01:33:39     INFO -  06-15 01:33:34.988 I/WindowManager( 1660): Destroying surface Surface(name=com.android.launcher3/com.android.launcher3.Launcher) called by com.android.server.wm.WindowStateAnimator.destroySurface:2014 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:881 com.android.server.wm.WindowState.destroyOrSaveSurface:2073 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3017 com.android.server.wm.WindowManagerService.relayoutWindow:2897 com.android.server.wm.Session.relayout:215 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:136
[task 2022-06-15T01:33:39.749Z] 01:33:39     INFO -  06-15 01:33:34.992 I/ActivityManager( 1660): Displayed org.mozilla.geckoview.test/.GeckoViewTestActivity: +83ms (total +96ms)
[task 2022-06-15T01:33:39.749Z] 01:33:39     INFO -  06-15 01:33:34.993 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008caa
[task 2022-06-15T01:33:39.749Z] 01:33:39     INFO -  06-15 01:33:35.003 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008741
[task 2022-06-15T01:33:39.750Z] 01:33:39     INFO -  06-15 01:33:35.007 D/GeckoViewModule( 3409): registerListener ["GeckoViewContent:ExitFullScreen","GeckoView:ClearMatches","GeckoView:DisplayMatches","GeckoView:FindInPage","GeckoView:RestoreState","GeckoView:ScrollBy","GeckoView:ScrollTo","GeckoView:SetActive","GeckoView:SetFocused","GeckoView:SetPriorityHint","GeckoView:UpdateInitData","GeckoView:ZoomToInput"]
[task 2022-06-15T01:33:39.750Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewNavigation( 3409): onInit
[task 2022-06-15T01:33:39.751Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewModule( 3409): registerListener ["GeckoView:GoBack","GeckoView:GoForward","GeckoView:GotoHistoryIndex","GeckoView:LoadUri","GeckoView:Reload","GeckoView:Stop","GeckoView:PurgeHistory"]
[task 2022-06-15T01:33:39.751Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewNavigation( 3409): onEnable
[task 2022-06-15T01:33:39.751Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewProcessHangMonitor( 3409): onInit
[task 2022-06-15T01:33:39.751Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewProcessHangMonitor( 3409): onEnable
[task 2022-06-15T01:33:39.752Z] 01:33:39     INFO -  06-15 01:33:35.008 D/GeckoViewModule( 3409): registerListener ["GeckoView:HangReportStop","GeckoView:HangReportWait"]
[task 2022-06-15T01:33:39.752Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewProgress( 3409): onEnable
[task 2022-06-15T01:33:39.752Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: visibilitychange
[task 2022-06-15T01:33:39.752Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewModule( 3409): registerListener GeckoView:FlushSessionState
[task 2022-06-15T01:33:39.752Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewSelectionAction( 3409): onEnable
[task 2022-06-15T01:33:39.753Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewModule( 3409): registerListener ["GeckoView:ExecuteSelectionAction"]
[task 2022-06-15T01:33:39.753Z] 01:33:39     INFO -  06-15 01:33:35.009 D/GeckoViewSettings( 3409): onInit
[task 2022-06-15T01:33:39.753Z] 01:33:39     INFO -  06-15 01:33:35.010 D/GeckoViewModule( 3409): registerListener ["GeckoView:GetUserAgent"]
[task 2022-06-15T01:33:39.754Z] 01:33:39     INFO -  06-15 01:33:35.010 D/GeckoViewSettings( 3409): onSettingsUpdate: {"chromeUri":null,"screenId":0,"userAgentOverride":null,"allowJavascript":true,"userAgentMode":0,"viewportMode":0,"useTrackingProtection":false,"suspendMediaWhenInactive":false,"usePrivateMode":false,"unsafeSessionContextId":null,"displayMode":0,"sessionContextId":null,"fullAccessibilityTree":false,"isPopup":false}
[task 2022-06-15T01:33:39.754Z] 01:33:39     INFO -  06-15 01:33:35.010 D/GeckoViewModule( 3409): registerListener ["GeckoView:WebExtension:SetTabActive"]
[task 2022-06-15T01:33:39.754Z] 01:33:39     INFO -  06-15 01:33:35.010 D/GeckoViewModule( 3409): registerListener ["ContentBlocking:RequestLog"]
[task 2022-06-15T01:33:39.755Z] 01:33:39     INFO -  06-15 01:33:35.011 D/GeckoViewMediaControl( 3409): onEnable
[task 2022-06-15T01:33:39.755Z] 01:33:39     INFO -  06-15 01:33:35.011 D/GeckoViewModule( 3409): registerListener ["GeckoView:MediaSession:Play","GeckoView:MediaSession:Pause","GeckoView:MediaSession:Stop","GeckoView:MediaSession:NextTrack","GeckoView:MediaSession:PrevTrack","GeckoView:MediaSession:SeekForward","GeckoView:MediaSession:SeekBackward","GeckoView:MediaSession:SkipAd","GeckoView:MediaSession:SeekTo","GeckoView:MediaSession:MuteAudio"]
[task 2022-06-15T01:33:39.755Z] 01:33:39     INFO -  06-15 01:33:35.012 I/GeckoSession( 3409): zerdatime 401048 - chrome startup finished
[task 2022-06-15T01:33:39.756Z] 01:33:39     INFO -  06-15 01:33:35.014 D/GeckoViewSettings[C]( 9830): receiveMessage: SettingsUpdate
[task 2022-06-15T01:33:39.756Z] 01:33:39     INFO -  06-15 01:33:35.017 W/GeckoEditableChild( 9830): No editable parent
[task 2022-06-15T01:33:39.756Z] 01:33:39     INFO -  06-15 01:33:35.018 D/GeckoViewXUL( 3409): receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
[task 2022-06-15T01:33:39.756Z] 01:33:39     INFO -  06-15 01:33:35.019 D/EGL_emulation(10035): eglMakeCurrent: 0x7c0ca607bd60: ver 3 0 (tinfo 0x7c0ca6110c00)
[task 2022-06-15T01:33:39.757Z] 01:33:39     INFO -  06-15 01:33:35.020 D/GeckoSession( 3409): handleMessage GeckoView:PageStart uri=about:blank
[task 2022-06-15T01:33:39.757Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStart(org.mozilla.geckoview.GeckoSession,java.lang.String)
[task 2022-06-15T01:33:39.757Z] 01:33:39     INFO -  06-15 01:33:35.020 D/GeckoSession( 3409): handleMessage GeckoView:LocationChange uri=about:blank
[task 2022-06-15T01:33:39.758Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onLocationChange(org.mozilla.geckoview.GeckoSession,java.lang.String,java.util.List)
[task 2022-06-15T01:33:39.758Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoBack(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-06-15T01:33:39.759Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): method: public abstract void org.mozilla.geckoview.GeckoSession$NavigationDelegate.onCanGoForward(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-06-15T01:33:39.759Z] 01:33:39     INFO -  06-15 01:33:35.020 D/GeckoSession( 3409): handleMessage GeckoView:PageStop uri=null
[task 2022-06-15T01:33:39.759Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): method: public abstract void org.mozilla.geckoview.GeckoSession$ProgressDelegate.onPageStop(org.mozilla.geckoview.GeckoSession,boolean)
[task 2022-06-15T01:33:39.760Z] 01:33:39     INFO -  06-15 01:33:35.020 E/GeckoSessionTestRule( 3409): after prepareStatement
[task 2022-06-15T01:33:39.760Z] 01:33:39     INFO -  06-15 01:33:35.023 D/GeckoViewModule( 3409): dispatch GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-06-15T01:33:39.760Z] 01:33:39     INFO -  06-15 01:33:35.023 D/LifecycleMonitor( 3409): Lifecycle status change: androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@6b24946 in: STOPPED
[task 2022-06-15T01:33:39.760Z] 01:33:39     INFO -  06-15 01:33:35.023 D/GeckoViewNavigation( 3409): onEvent: event=GeckoView:LoadUri, data={"uri":"http://localhost:4245/assets/www/inputs.html","flags":0,"headerFilter":1}
[task 2022-06-15T01:33:39.762Z] 01:33:39     INFO -  06-15 01:33:35.023 V/ActivityScenario( 3409): Activity lifecycle changed event received but ignored because the intent does not match. startActivityIntent=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] cmp=org.mozilla.geckoview.test/.GeckoViewTestActivity }, activity.getIntent()=Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10008000 cmp=org.mozilla.geckoview.test/androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity (has extras) }, activity=androidx.test.core.app.InstrumentationActivityInvoker$BootstrapActivity@6b24946
[task 2022-06-15T01:33:39.763Z] 01:33:39     INFO -  06-15 01:33:35.029 D/GeckoViewProgress( 3409): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2022-06-15T01:33:39.763Z] 01:33:39     INFO -  06-15 01:33:35.030 D/GeckoViewProgress( 3409): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.763Z] 01:33:39     INFO -  06-15 01:33:35.030 D/GeckoViewProgress( 3409): ProgressTracker start http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.764Z] 01:33:39     INFO -  06-15 01:33:35.031 D/GeckoViewProgress( 3409): ProgressTracker updateProgress
[task 2022-06-15T01:33:39.764Z] 01:33:39     INFO -  06-15 01:33:35.031 D/GeckoViewProgress( 3409): ProgressTracker updateProgress data={"prev":0,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2022-06-15T01:33:39.764Z] 01:33:39     INFO -  06-15 01:33:35.031 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008caa
[task 2022-06-15T01:33:39.765Z] 01:33:39     INFO -  06-15 01:33:35.031 D/GeckoSession( 3409): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-06-15T01:33:39.765Z] 01:33:39     INFO -  06-15 01:33:35.035 D/GeckoViewProgress( 3409): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2022-06-15T01:33:39.766Z] 01:33:39     INFO -  06-15 01:33:35.036 D/GeckoSession( 3409): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.766Z] 01:33:39     INFO -  06-15 01:33:35.041 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: visibilitychange
[task 2022-06-15T01:33:39.766Z] 01:33:39     INFO -  06-15 01:33:35.042 D/GeckoViewAutoFill[C]( 9830): handleEvent: pagehide
[task 2022-06-15T01:33:39.767Z] 01:33:39     INFO -  06-15 01:33:35.043 D/GeckoViewAutoFill( 3409): receiveMessage Clear
[task 2022-06-15T01:33:39.767Z] 01:33:39     INFO -  06-15 01:33:35.049 W/Web Content( 9830): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:4245/assets/www/inputs.html" line: 0}]
[task 2022-06-15T01:33:39.768Z] 01:33:39     INFO -  06-15 01:33:35.051 D/GeckoViewNavigation( 3409): onLocationChange
[task 2022-06-15T01:33:39.768Z] 01:33:39     INFO -  06-15 01:33:35.052 D/GeckoViewProgress( 3409): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-06-15T01:33:39.769Z] 01:33:39     INFO -  06-15 01:33:35.052 D/GeckoViewProgress( 3409): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2022-06-15T01:33:39.769Z] 01:33:39     INFO -  06-15 01:33:35.052 D/GeckoViewProgress( 3409): ProgressTracker changeLocation http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.769Z] 01:33:39     INFO -  06-15 01:33:35.052 D/GeckoViewProgress( 3409): onSecurityChange
[task 2022-06-15T01:33:39.769Z] 01:33:39     INFO -  06-15 01:33:35.053 D/GeckoSession( 3409): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.053 D/GeckoSession( 3409): handleMessage GeckoView:SecurityChanged uri=null
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.062 D/GeckoViewContent( 3409): handleEvent: pagetitlechanged
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.064 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: DOMContentLoaded
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: DOMContentLoaded
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoViewProgress( 3409): receiveMessage: DOMContentLoaded
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoViewProgress( 3409): ProgressTracker handleEvent: DOMContentLoaded
[task 2022-06-15T01:33:39.770Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoViewProgress( 3409): ProgressTracker updateProgress
[task 2022-06-15T01:33:39.771Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoViewProgress( 3409): 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 2022-06-15T01:33:39.771Z] 01:33:39     INFO -  06-15 01:33:35.065 D/GeckoSession( 3409): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-06-15T01:33:39.771Z] 01:33:39     INFO -  06-15 01:33:35.076 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: MozFirstContentfulPaint
[task 2022-06-15T01:33:39.771Z] 01:33:39     INFO -  06-15 01:33:35.077 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.771Z] 01:33:39     INFO -  06-15 01:33:35.082 D/GeckoViewNavigation( 3409): onLocationChange
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.083 W/Web Content( 9830): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:4245/assets/www/selectionAction_frame.html" line: 0}]
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.083 D/GeckoSession( 3409): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/selectionAction_frame.html
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.083 D/GeckoViewProgress( 3409): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/selectionAction_frame.html, flags=0
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.086 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: DOMContentLoaded
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.088 D/GeckoViewContent[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.088 D/GeckoViewAutoFill[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.772Z] 01:33:39     INFO -  06-15 01:33:35.092 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008caa
[task 2022-06-15T01:33:39.773Z] 01:33:39     INFO -  06-15 01:33:35.093 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: MozAfterPaint
[task 2022-06-15T01:33:39.773Z] 01:33:39     INFO -  06-15 01:33:35.094 D/GeckoViewProgress( 3409): receiveMessage: MozAfterPaint
[task 2022-06-15T01:33:39.773Z] 01:33:39     INFO -  06-15 01:33:35.094 D/GeckoViewProgress( 3409): ProgressTracker handleEvent: MozAfterPaint
[task 2022-06-15T01:33:39.773Z] 01:33:39     INFO -  06-15 01:33:35.094 D/GeckoViewProgress( 3409): ProgressTracker updateProgress
[task 2022-06-15T01:33:39.774Z] 01:33:39     INFO -  06-15 01:33:35.095 D/GeckoViewProgress( 3409): ProgressTracker updateProgress data={"prev":55,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":true,"pageShow":false,"parsed":true} progress=80
[task 2022-06-15T01:33:39.774Z] 01:33:39     INFO -  06-15 01:33:35.095 D/GeckoSession( 3409): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-06-15T01:33:39.774Z] 01:33:39     INFO -  06-15 01:33:35.098 D/GeckoViewNavigation( 3409): onLocationChange
[task 2022-06-15T01:33:39.774Z] 01:33:39     INFO -  06-15 01:33:35.098 W/Web Content( 9830): [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:4245/assets/www/selectionAction_frame.html" line: 0}]
[task 2022-06-15T01:33:39.775Z] 01:33:39     INFO -  06-15 01:33:35.099 D/GeckoViewProgress( 3409): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/selectionAction_frame.html, flags=0
[task 2022-06-15T01:33:39.775Z] 01:33:39     INFO -  06-15 01:33:35.099 D/GeckoSession( 3409): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/selectionAction_frame.html
[task 2022-06-15T01:33:39.775Z] 01:33:39     INFO -  06-15 01:33:35.102 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: DOMContentLoaded
[task 2022-06-15T01:33:39.775Z] 01:33:39     INFO -  06-15 01:33:35.105 D/GeckoViewContent[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.105 D/GeckoViewAutoFill[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.114 D/GeckoViewContent[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.114 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.114 D/GeckoViewAutoFill[C]( 9830): handleEvent: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.115 D/WebExtension( 3409): handleMessage GeckoView:WebExtension:Connect
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.117 D/GeckoViewProgress( 3409): receiveMessage: pageshow
[task 2022-06-15T01:33:39.776Z] 01:33:39     INFO -  06-15 01:33:35.117 D/GeckoViewProgress( 3409): ProgressTracker handleEvent: pageshow
[task 2022-06-15T01:33:39.777Z] 01:33:39     INFO -  06-15 01:33:35.117 D/GeckoViewProgress( 3409): ProgressTracker updateProgress
[task 2022-06-15T01:33:39.777Z] 01:33:39     INFO -  06-15 01:33:35.117 D/GeckoViewProgress( 3409): ProgressTracker updateProgress data={"prev":80,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":true,"pageShow":true,"parsed":true} progress=100
[task 2022-06-15T01:33:39.777Z] 01:33:39     INFO -  06-15 01:33:35.117 D/GeckoSession( 3409): handleMessage GeckoView:ProgressChanged uri=null
[task 2022-06-15T01:33:39.777Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: MozAfterPaint
[task 2022-06-15T01:33:39.777Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewProgress( 3409): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: MozFirstContentfulPaint
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewProgress( 3409): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewProgress( 3409): ProgressTracker stop
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewProgress( 3409): ProgressTracker updateProgress
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.118 D/GeckoViewContentDelegateChild[C]( 9830): handleEvent: MozFirstContentfulPaint
[task 2022-06-15T01:33:39.778Z] 01:33:39     INFO -  06-15 01:33:35.119 D/GeckoViewProgress( 3409): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2022-06-15T01:33:39.779Z] 01:33:39     INFO -  06-15 01:33:35.119 D/GeckoSession( 3409): handleMessage GeckoView:PageStop uri=null
[task 2022-06-15T01:33:39.779Z] 01:33:39     INFO -  06-15 01:33:35.120 D/GeckoViewProgress( 3409): receiveMessage: MozAfterPaint
[task 2022-06-15T01:33:39.779Z] 01:33:39     INFO -  06-15 01:33:35.120 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.779Z] 01:33:39     INFO -  06-15 01:33:35.120 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.779Z] 01:33:39     INFO -  06-15 01:33:35.121 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.780Z] 01:33:39     INFO -  06-15 01:33:35.121 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"e5aef731-fed4-4097-b6b8-1a132f7a969c","eval":"document.querySelector('#designmode').contentWindow.focus()"}}
[task 2022-06-15T01:33:39.780Z] 01:33:39     INFO -  06-15 01:33:35.121 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.780Z] 01:33:39     INFO -  06-15 01:33:35.122 D/GeckoViewContentDelegateParent( 3409): receiveMessage: DispatcherMessage
[task 2022-06-15T01:33:39.780Z] 01:33:39     INFO -  06-15 01:33:35.124 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"d70b6c61-5aee-47e6-9b4a-00e0293a4942","args":{"oldPrefs":{},"newPrefs":{"geckoview.selection_action.show_on_focus":true,"layout.accessiblecaret.script_change_update_mode":2}},"type":"SetPrefs"}}
[task 2022-06-15T01:33:39.781Z] 01:33:39     INFO -  06-15 01:33:35.128 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"a816ac03-b2c7-4ebb-9c9b-1978bb7e804f","eval":"(function() {\n                    var doc = document.querySelector('#designmode').contentDocument;\n                    var text = doc.body.firstChild;\n                    doc.getSelection().setBaseAndExtent(text, 0, text, 11);\n                })()"}}
[task 2022-06-15T01:33:39.781Z] 01:33:39     INFO -  06-15 01:33:35.131 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008caa
[task 2022-06-15T01:33:39.781Z] 01:33:39     INFO -  06-15 01:33:35.134 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.781Z] 01:33:39     INFO -  06-15 01:33:35.134 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.781Z] 01:33:39     INFO -  06-15 01:33:35.139 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.139 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.140 D/GeckoViewModule( 3409): dispatch GeckoView:ExecuteSelectionAction, data={"actionId":"{7c6e2e02-dd90-4e35-8ab3-199846369b15}","id":"org.mozilla.geckoview.CUT"}
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.140 D/GeckoViewSelectionAction( 3409): onEvent: GeckoView:ExecuteSelectionAction
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.142 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.142 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.144 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: MozAfterPaint
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.144 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.782Z] 01:33:39     INFO -  06-15 01:33:35.144 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.144 D/GeckoViewProgress( 3409): receiveMessage: MozAfterPaint
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.146 D/GeckoViewSelectionActionDelegate[C]( 9830): receiveMessage ExecuteSelectionAction
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.146 D/GeckoViewSelectionActionDelegate[C]( 9830): Performing org.mozilla.geckoview.CUT
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.148 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"4e397301-32c8-40c4-b9a6-be372c3485c9","eval":"document.querySelector('#designmode').contentDocument.body.textContent"}}
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.150 E/eglCodecCommon(10035): glUtilsParamSize: unknow param 0x00008caa
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.152 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.152 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.154 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.783Z] 01:33:39     INFO -  06-15 01:33:35.154 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.156 D/GeckoViewContent[C]( 9830): handleEvent: mozcaretstatechanged
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.156 D/GeckoViewSelectionActionDelegate[C]( 9830): handleEvent: updateposition
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.159 D/GeckoViewProgressDelegate[C]( 9830): handleEvent: MozAfterPaint
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.160 D/GeckoViewProgress( 3409): receiveMessage: MozAfterPaint
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.160 E/GeckoSessionTestRule( 3409): after evaluate
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.161 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"31301970-d581-435c-8c3f-26330a150929","args":{"oldPrefs":{"geckoview.selection_action.show_on_focus":null,"layout.accessiblecaret.script_change_update_mode":1}},"type":"RestorePrefs"}}
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.163 E/GeckoSessionTestRule( 3409): after performTestEndCheck
[task 2022-06-15T01:33:39.784Z] 01:33:39     INFO -  06-15 01:33:35.163 D/EGL_emulation(10035): eglMakeCurrent: 0x7c0ca607bd60: ver 3 0 (tinfo 0x7c0ca6110c00)
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  06-15 01:33:35.165 D/GeckoViewContent( 3409): handleEvent: DOMWindowClose
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  06-15 01:33:35.165 D/GeckoViewConsole( 3409): onEvent GeckoView:WebExtension:List null
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  06-15 01:33:35.166 E/GeckoSessionTestRule( 3409): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 cut[#designmode](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2022-06-15T01:33:39.785Z] 01:33:39  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SelectionActionDelegateTest#cut[#designmode] | java.lang.RuntimeException: android.os.DeadSystemException
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  TEST-INFO took 4488ms
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_ABORTED: System has crashed.
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  Passed: 602
[task 2022-06-15T01:33:39.785Z] 01:33:39  WARNING -  Failed: 1
[task 2022-06-15T01:33:39.785Z] 01:33:39  WARNING -  One or more unittests failed.
[task 2022-06-15T01:33:39.785Z] 01:33:39     INFO -  Todo: 43
[task 2022-06-15T01:33:39.785Z] 01:33:39  WARNING -  TEST-UNEXPECTED-FAIL | runjunit.py | Some tests did not run (probably due to a crash in the harness)
[task 2022-06-15T01:33:39.786Z] 01:33:39     INFO -  SUITE-END | took 370s
[task 2022-06-15T01:33:39.858Z] 01:33:39     INFO -  mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QiTL-njFTJmni-iRj4p1Qw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2022-06-15T01:33:45.817Z] 01:33:45     INFO -  mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /tmp/tmpoh7zuue3/6d09758c-e60d-c3c6-7329-02666b82e34f.dmp /tmp/tmpo5gtqaa5
[task 2022-06-15T01:33:51.504Z] 01:33:51     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/6d09758c-e60d-c3c6-7329-02666b82e34f.dmp
[task 2022-06-15T01:33:51.504Z] 01:33:51     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/6d09758c-e60d-c3c6-7329-02666b82e34f.extra
[task 2022-06-15T01:33:51.511Z] 01:33:51  WARNING -  PROCESS-CRASH | org.mozilla.geckoview.test.SelectionActionDelegateTest#cut[#designmode] | android.os.DeadSystemException 	at android.app.ContextImpl.unbindService(ContextImpl.java:1516)
[task 2022-06-15T01:33:51.511Z] 01:33:51     INFO -  [android.os.DeadSystemException
[task 2022-06-15T01:33:51.511Z] 01:33:51     INFO -  	at android.app.ContextImpl.unbindService(ContextImpl.java:1516)
[task 2022-06-15T01:33:51.511Z] 01:33:51     INFO -  	at android.content.ContextWrapper.unbindService(ContextWrapper.java:648)
[task 2022-06-15T01:33:51.512Z] 01:33:51     INFO -  	at org.mozilla.gecko.process.ServiceAllocator$InstanceInfo.updateBindings(ServiceAllocator.java:332)
[task 2022-06-15T01:33:51.512Z] 01:33:51     INFO -  	at org.mozilla.gecko.process.ServiceAllocator$InstanceInfo.setPriorityLevel(ServiceAllocator.java:192)
[task 2022-06-15T01:33:51.512Z] 01:33:51     INFO -  	at org.mozilla.gecko.process.GeckoProcessManager.lambda$setProcessPriority$4(GeckoProcessManager.java:732)
[task 2022-06-15T01:33:51.513Z] 01:33:51     INFO -  	at org.mozilla.gecko.process.GeckoProcessManager$$ExternalSyntheticLambda6.run(Unknown Source)
[task 2022-06-15T01:33:51.513Z] 01:33:51     INFO -  	at org.mozilla.gecko.util.XPCOMEventTarget$JNIRunnable.run(XPCOMEventTarget.java:122)
[task 2022-06-15T01:33:51.513Z] 01:33:51     INFO -  ]
[task 2022-06-15T01:33:51.513Z] 01:33:51     INFO -  Mozilla crash reason: MOZ_CRASH(JNI exception)
[task 2022-06-15T01:33:51.514Z] 01:33:51     INFO -  Crash dump filename: /tmp/tmpoh7zuue3/6d09758c-e60d-c3c6-7329-02666b82e34f.dmp
[task 2022-06-15T01:33:51.580Z] 01:33:51     INFO -  Stopping web server
[task 2022-06-15T01:33:51.584Z] 01:33:51     INFO -  Server shut down.
[task 2022-06-15T01:33:51.605Z] 01:33:51     INFO -  Web server killed.
[task 2022-06-15T01:33:51.605Z] 01:33:51     INFO -  Stopping web socket server
[task 2022-06-15T01:33:51.625Z] 01:33:51     INFO -  Stopping ssltunnel
[task 2022-06-15T01:33:52.095Z] 01:33:52    ERROR - Return code: 1
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - TinderboxPrint: geckoview-junit<br/>602/<em class="testfail">1</em>
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - ##### geckoview-junit log ends
[task 2022-06-15T01:33:52.095Z] 01:33:52  WARNING - # TBPL WARNING #
[task 2022-06-15T01:33:52.095Z] 01:33:52  WARNING - setting return code to 1
[task 2022-06-15T01:33:52.095Z] 01:33:52  WARNING - The geckoview-junit suite: geckoview-junit ran with return status: WARNING
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - Running post-action listener: _package_coverage_data
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - Running post-action listener: _resource_record_post_action
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - Running post-action listener: process_java_coverage_data
[task 2022-06-15T01:33:52.095Z] 01:33:52     INFO - Running post-action listener: stop_device
[task 2022-06-15T01:33:52.743Z] 01:33:52     INFO - /data/tombstones/tombstone_00 deleted
[task 2022-06-15T01:33:52.743Z] 01:33:52     INFO - Killing logcat pid 1963.
[task 2022-06-15T01:33:52.743Z] 01:33:52     INFO - Killing every process called qemu-system-x86_64
[task 2022-06-15T01:33:52.759Z] 01:33:52     INFO - [mozharness: 2022-06-15 01:33:52.758688Z] Finished run-tests step (success)
[task 2022-06-15T01:33:52.759Z] 01:33:52     INFO - Running post-run listener: _resource_record_post_run
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - Total resource usage - Wall time: 419s; CPU: 40%; Read bytes: 0; Write bytes: 184016896; Read time: 0; Write time: 11396
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: CPU usage<br/>40.3%
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: I/O write bytes / time<br/>184,016,896 / 11,396
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: CPU guest<br/>6,299.8 (13.7%)
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: CPU idle<br/>23,695.5 (51.5%)
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: CPU system<br/>2,435.8 (5.3%)
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: CPU user<br/>13,526.2 (29.4%)
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2022-06-15T01:33:52.958Z] 01:33:52     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-06-15T01:33:52.962Z] 01:33:52     INFO - verify-device - Wall time: 30s; CPU: 32%; Read bytes: 0; Write bytes: 2879488; Read time: 0; Write time: 403
[task 2022-06-15T01:33:52.963Z] 01:33:52     INFO - install - Wall time: 3s; CPU: 34%; Read bytes: 0; Write bytes: 24576; Read time: 0; Write time: 1
[task 2022-06-15T01:33:53.008Z] 01:33:53     INFO - run-tests - Wall time: 386s; CPU: 41%; Read bytes: 0; Write bytes: 181112832; Read time: 0; Write time: 10992
[task 2022-06-15T01:33:53.894Z] 01:33:53  WARNING - returning nonzero exit status 1
[task 2022-06-15T01:33:53.984Z] cleanup
[task 2022-06-15T01:33:53.984Z] + cleanup
[task 2022-06-15T01:33:53.984Z] + local rv=1
[task 2022-06-15T01:33:53.984Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2022-06-15T01:33:53.984Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2022-06-15T01:33:53.986Z] + '[' ']'
[task 2022-06-15T01:33:53.986Z] + true
[task 2022-06-15T01:33:53.986Z] + cleanup_xvfb
[task 2022-06-15T01:33:53.987Z] ++ pidof Xvfb
[task 2022-06-15T01:33:53.993Z] + local xvfb_pid=54
[task 2022-06-15T01:33:53.993Z] + local vnc=false
[task 2022-06-15T01:33:53.994Z] + local interactive=false
[task 2022-06-15T01:33:53.994Z] + '[' -n 54 ']'
[task 2022-06-15T01:33:53.994Z] + [[ false == false ]]
[task 2022-06-15T01:33:53.994Z] + [[ false == false ]]
[task 2022-06-15T01:33:53.994Z] + kill 54
[task 2022-06-15T01:33:53.994Z] + screen -XS xvfb quit
[task 2022-06-15T01:33:53.999Z] + exit 1
[taskcluster 2022-06-15 01:33:54.772Z] === Task Finished ===
[taskcluster 2022-06-15 01:34:02.845Z] Unsuccessful task run with exit code: 1 completed in 543.848 seconds
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.