Closed Bug 1697021 Opened 4 years ago Closed 4 years ago

Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#delete[#textarea] | java.lang.AssertionError: Changed content should match

Categories

(GeckoView :: General, defect, P5)

Unspecified
All
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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


[task 2021-03-08T19:45:52.761Z] 19:45:52     INFO -  03-08 19:45:52.021 E/JavaBinder( 1617): !!! FAILED BINDER TRANSACTION !!!  (parcel size = 76)
[task 2021-03-08T19:45:52.761Z] 19:45:52     INFO -  03-08 19:45:52.022 E/JavaBinder( 1617): !!! FAILED BINDER TRANSACTION !!!  (parcel size = 1784)
[task 2021-03-08T19:45:52.761Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): Application dead when creating service ServiceRecord{15260d0 u0 org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab0}
[task 2021-03-08T19:45:52.761Z] 19:45:52     INFO -  03-08 19:45:52.023 I/ActivityManager( 1617): Process org.mozilla.geckoview.test:tab0 (pid 20314) has died
[task 2021-03-08T19:45:52.761Z] 19:45:52     INFO -  03-08 19:45:52.023 D/ActivityManager( 1617): cleanUpApplicationRecord -- 20314
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): Scheduling restart of crashed service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab0 in 1000ms
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): Scheduling restart of crashed service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab0 in 4000ms
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): Exception when starting service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab0
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at android.os.BinderProxy.transactNative(Native Method)
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at android.os.BinderProxy.transact(Binder.java:615)
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at android.app.ApplicationThreadProxy.scheduleCreateService(ApplicationThreadNative.java:986)
[task 2021-03-08T19:45:52.762Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at com.android.server.am.ActiveServices.realStartServiceLocked(ActiveServices.java:1796)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at com.android.server.am.ActiveServices.bringUpServiceLocked(ActiveServices.java:1699)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at com.android.server.am.ActiveServices.bindServiceLocked(ActiveServices.java:1025)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at com.android.server.am.ActivityManagerService.bindService(ActivityManagerService.java:17136)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at android.app.ActivityManagerNative.onTransact(ActivityManagerNative.java:1134)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:2757)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.023 W/ActivityManager( 1617): 	at android.os.Binder.execTransact(Binder.java:565)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.030 I/art     (20361): Not late-enabling -Xcheck:jni (already on)
[task 2021-03-08T19:45:52.763Z] 19:45:52     INFO -  03-08 19:45:52.030 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.030 W/art     (20361): Unexpected CPU variant for X86 using defaults: x86_64
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.034 I/ActivityManager( 1617): Start proc 20361:org.mozilla.geckoview.test:tab0/u0a62 for service org.mozilla.geckoview.test/org.mozilla.gecko.process.GeckoChildProcessServices$tab0
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.035 D/ServiceAllocator( 3146): org.mozilla.gecko.process.GeckoChildProcessServices$tab0 updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.048 I/MultiDex(20361): VM with version 2.1.0 has multidex support
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.048 I/MultiDex(20361): Installing application
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.048 I/MultiDex(20361): VM has multidex support, MultiDex support library is disabled.
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.051 D/GeckoThread(20361): State changed to LAUNCHED
[task 2021-03-08T19:45:52.764Z] 19:45:52     INFO -  03-08 19:45:52.051 I/GeckoThread(20361): preparing to run Gecko
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.054 D/ServiceAllocator( 3146): org.mozilla.gecko.process.GeckoChildProcessServices$tab0 updateBindings: FOREGROUND priority, 2 importance, 0 successful binds, 0 failed binds, 0 successful unbinds
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_CRASHREPORTER=1
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_WEBRENDER=1
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: XPCOM_DEBUG_BREAK=stack
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: R_LOG_VERBOSE=1
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_FORCE_ENABLE_FISSION=1
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_DISABLE_NONLOCAL_CONNECTIONS=1
[task 2021-03-08T19:45:52.765Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_IN_AUTOMATION=1
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: MOZ_CRASHREPORTER_SHUTDOWN=1
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: R_LOG_DESTINATION=stderr
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoThread(20361): env var: R_LOG_LEVEL=6
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.055 D/GeckoViewXUL( 3146): WillChangeBrowserRemoteness
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.056 D/GeckoViewModule( 3146): unregisterListener
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.056 D/GeckoViewMediaControl( 3146): onDisable
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.056 D/GeckoViewModule( 3146): unregisterListener
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.057 D/GeckoViewMedia[C](17435): onDisable
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.057 D/GeckoThread(20361): State changed to MOZGLUE_READY
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.057 D/GeckoViewMediaControl[C](17435): onDisable
[task 2021-03-08T19:45:52.766Z] 19:45:52     INFO -  03-08 19:45:52.058 D/GeckoViewXUL( 3146): DidChangeBrowserRemoteness
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.058 D/GeckoViewNavigation( 3146): sessionContextId=null
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.058 D/GeckoViewSelectionActionDelegate[C](17435): handleEvent: visibilitychange
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewModule( 3146): enableQueuing true
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewModule( 3146): registerListener ["GeckoView:MediaObserve","GeckoView:MediaUnobserve","GeckoView:MediaPlay","GeckoView:MediaPause","GeckoView:MediaSeek","GeckoView:MediaSetVolume","GeckoView:MediaSetMuted","GeckoView:MediaSetPlaybackRate"]
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewModule( 3146): enableQueuing true
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewMediaControl( 3146): onEnable
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewAutofill[C](17435): handleEvent: pagehide
[task 2021-03-08T19:45:52.767Z] 19:45:52     INFO -  03-08 19:45:52.059 D/GeckoViewModule( 3146): registerListener ["GeckoView:MediaSession:Play","GeckoView:MediaSession:Pause","GeckoView:MediaSession:Stop","GeckoView:MediaSession:NextTrack","GeckoView:MediaSession:PrevTrack","GeckoView:MediaSession:SeekForward","GeckoView:MediaSession:SeekBackward","GeckoView:MediaSession:SkipAd","GeckoView:MediaSession:SeekTo","GeckoView:MediaSession:MuteAudio"]
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.060 D/GeckoViewAutofill(17435): Clearing auto-fill
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.063 E/GeckoLibLoad(20361): Load sqlite start
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.064 E/GeckoLibLoad(20361): Load sqlite done
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.064 E/GeckoLibLoad(20361): Load nss start
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.064 E/GeckoLibLoad(20361): Load nss done
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.065 D/ServiceAllocator( 3146): org.mozilla.gecko.process.GeckoChildProcessServices$tab0 updateBindings: IDLE priority, 6 importance, 0 successful binds, 0 failed binds, 2 successful unbinds
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.065 D/GeckoViewContent( 3146): observe: ipc:content-shutdown
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.066 I/Gecko   ( 3146): [Parent 3146, Unnamed thread 796692d22010] WARNING: waitpid failed pid:20314 errno:10: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:238
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.072 E/GeckoLibLoad(20361): Loaded libs in 8.073697ms total, 0ms(0ms) user, 0ms(10ms) system, 0(0) faults
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.072 D/GeckoThread(20361): State changed to LIBS_READY
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.072 W/GeckoThread(20361): zerdatime 336459 - runGecko
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.084 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.097 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.768Z] 19:45:52     INFO -  03-08 19:45:52.140 D/GeckoViewStartup(20361): observe: app-startup
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.143 D/GeckoViewConsole(20361): enabled = true
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.146 D/GeckoThread(20361): State changed to RUNNING
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.159 W/ResourceType(20361): Too many attribute references, stopped at: 0x01010099
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.165 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.173 D/GeckoViewXUL( 3146): receiveMessage GeckoView:ContentModuleLoaded {}
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.175 D/GeckoViewAutofill[C](20361): onInit
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.175 D/GeckoViewXUL( 3146): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewAutofill"}
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.178 D/GeckoViewXUL( 3146): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMedia"}
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.178 D/GeckoViewModule( 3146): enableQueuing false
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.178 D/GeckoViewModule( 3146): dispatchQueued
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.179 D/GeckoViewMedia[C](20361): onEnable
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.180 D/GeckoViewMediaControl[C](20361): onEnable
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.181 D/GeckoViewXUL( 3146): receiveMessage GeckoView:ContentModuleLoaded {"module":"GeckoViewMediaControl"}
[task 2021-03-08T19:45:52.769Z] 19:45:52     INFO -  03-08 19:45:52.181 D/GeckoViewModule( 3146): enableQueuing false
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.181 D/GeckoViewModule( 3146): dispatchQueued
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.182 D/GeckoViewMediaControl[C](20361): onEnable
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.186 D/GeckoNetworkManager(20361): Incoming event enableNotifications for state OffNoListeners -> OffWithListeners
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.187 D/GeckoNetworkManager(20361): New network state: UP, CELLULAR, CELL_4G
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.188 D/GeckoViewProgress( 3146): ProgressTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.188 D/GeckoViewProgress( 3146): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.188 D/GeckoViewProgress( 3146): ProgressTracker start http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.189 D/GeckoViewProgress( 3146): ProgressTracker updateProgress
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.189 D/GeckoViewProgress( 3146): ProgressTracker updateProgress data={"prev":0,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":false,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":false} progress=15
[task 2021-03-08T19:45:52.770Z] 19:45:52     INFO -  03-08 19:45:52.189 D/GeckoSession( 3146): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.189 D/GeckoViewProgress( 3146): StateTracker onStateChange: isTopLevel=true, flags=0xf0001, status=NS_OK loadType=1
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.189 D/GeckoSession( 3146): handleMessage GeckoView:PageStart uri=http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.193 D/GeckoViewMedia[C](20361): handleEvent: pagehide
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.196 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.197 D/GeckoViewSelectionActionDelegate[C](20361): handleEvent: visibilitychange
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.197 D/GeckoViewAutofill[C](20361): handleEvent: pagehide
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.200 D/GeckoViewAutofill(20361): Clearing auto-fill
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.205 D/GeckoViewNavigation( 3146): onLocationChange
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.205 D/GeckoSession( 3146): handleMessage GeckoView:LocationChange uri=http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.205 D/GeckoViewProgress( 3146): SecurityTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.206 D/GeckoViewProgress( 3146): ProgressTracker onLocationChange: location=http://localhost:4245/assets/www/inputs.html, flags=0
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.206 D/GeckoViewProgress( 3146): ProgressTracker changeLocation http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.206 D/GeckoViewProgress( 3146): onSecurityChange
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.206 D/GeckoSession( 3146): handleMessage GeckoView:SecurityChanged uri=null
[task 2021-03-08T19:45:52.771Z] 19:45:52     INFO -  03-08 19:45:52.240 D/GeckoViewContent( 3146): handleEvent: pagetitlechanged
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.243 D/GeckoViewContentDelegateChild[C](20361): handleEvent: DOMContentLoaded
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.244 D/GeckoViewProgressDelegate[C](20361): handleEvent: DOMContentLoaded
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.255 D/GeckoViewProgress( 3146): receiveMessage: DOMContentLoaded
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.255 D/GeckoViewProgress( 3146): ProgressTracker handleEvent: DOMContentLoaded
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.255 D/GeckoViewProgress( 3146): ProgressTracker updateProgress
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.255 D/GeckoViewProgress( 3146): ProgressTracker updateProgress data={"prev":15,"uri":"http://localhost:4245/assets/www/inputs.html","locationChange":true,"pageStart":true,"pageStop":false,"firstPaint":false,"pageShow":false,"parsed":true} progress=55
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.255 D/GeckoSession( 3146): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.264 D/GeckoViewContentDelegateChild[C](20361): handleEvent: DOMContentLoaded
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.267 D/GeckoViewContent[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.267 D/GeckoViewAutofill[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.772Z] 19:45:52     INFO -  03-08 19:45:52.269 D/GeckoViewContentDelegateChild[C](20361): handleEvent: MozFirstContentfulPaint
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.281 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.299 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.302 D/GeckoViewProgressDelegate[C](20361): handleEvent: MozAfterPaint
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.302 D/GeckoViewProgress( 3146): receiveMessage: MozAfterPaint
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.302 D/GeckoViewProgress( 3146): ProgressTracker handleEvent: MozAfterPaint
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.302 D/GeckoViewProgress( 3146): ProgressTracker updateProgress
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.303 D/GeckoViewProgress( 3146): 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 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.303 D/GeckoSession( 3146): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.304 D/GeckoViewContentDelegateChild[C](20361): handleEvent: DOMContentLoaded
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.305 D/GeckoViewContent[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.305 D/GeckoViewAutofill[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.773Z] 19:45:52     INFO -  03-08 19:45:52.319 D/GeckoViewProgressDelegate[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.319 D/WebExtension( 3146): handleMessage GeckoView:WebExtension:Connect
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.319 D/GeckoViewContent[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoViewAutofill[C](20361): handleEvent: pageshow
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoViewProgress( 3146): receiveMessage: pageshow
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoViewProgress( 3146): ProgressTracker handleEvent: pageshow
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoViewProgress( 3146): ProgressTracker updateProgress
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoViewProgress( 3146): 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 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.320 D/GeckoSession( 3146): handleMessage GeckoView:ProgressChanged uri=null
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoViewProgress( 3146): ProgressTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoViewProgress( 3146): ProgressTracker onStateChange: uri=http://localhost:4245/assets/www/inputs.html
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoViewProgress( 3146): ProgressTracker stop
[task 2021-03-08T19:45:52.774Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoViewProgress( 3146): ProgressTracker updateProgress
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoViewProgress( 3146): StateTracker onStateChange: isTopLevel=true, flags=0xc0010, status=NS_OK loadType=1
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.321 D/GeckoSession( 3146): handleMessage GeckoView:PageStop uri=null
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.322 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"1a165518-2dc6-4a73-bc19-ec07f43aadbb","eval":"document.querySelector('#textarea').focus()"}}
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.322 D/GeckoViewContentDelegateChild[C](20361): handleEvent: MozFirstContentfulPaint
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.322 D/GeckoViewContentDelegateChild[C](20361): handleEvent: MozFirstContentfulPaint
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.324 D/GeckoViewAutofill[C](20361): handleEvent: focusin
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.326 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"b9f8146b-1590-47bf-9078-f602f9616ff1","args":{"oldPrefs":{},"newPrefs":{"geckoview.selection_action.show_on_focus":true,"layout.accessiblecaret.script_change_update_mode":2}},"type":"SetPrefs"}}
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.330 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"e810be33-4b6c-46e2-8e51-3536af90a454","eval":"document.querySelector('#textarea').select()"}}
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.331 E/eglCodecCommon( 3146): glUtilsParamSize: unknow param 0x00008caa
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.335 D/GeckoViewSelectionActionDelegate[C](20361): handleEvent: updateposition
[task 2021-03-08T19:45:52.775Z] 19:45:52     INFO -  03-08 19:45:52.337 D/GeckoViewContent[C](20361): handleEvent: mozcaretstatechanged
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.337 D/GeckoViewSelectionActionDelegate[C](20361): handleEvent: updateposition
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.339 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"44a73399-ac4f-43cb-982b-f24ad0528497","eval":"document.querySelector('#textarea').value"}}
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.339 D/GeckoViewContent[C](20361): handleEvent: mozcaretstatechanged
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.339 D/GeckoViewProgressDelegate[C](20361): handleEvent: MozAfterPaint
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.340 D/GeckoViewSelectionActionDelegate[C](20361): handleEvent: updateposition
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.340 D/GeckoViewProgress( 3146): receiveMessage: MozAfterPaint
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.341 D/GeckoViewContent[C](20361): handleEvent: mozcaretstatechanged
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.342 W/GeckoViewSelectionActionDelegate[C](20361): Stale response org.mozilla.geckoview.DELETE
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.343 E/GeckoSessionTestRule( 3146): after evaluate
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.343 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:PortMessageFromApp {"message":{"id":"fe9ff12e-366b-4c17-8a59-c32173528914","args":{"oldPrefs":{"geckoview.selection_action.show_on_focus":null,"layout.accessiblecaret.script_change_update_mode":1}},"type":"RestorePrefs"}}
[task 2021-03-08T19:45:52.776Z] 19:45:52     INFO -  03-08 19:45:52.346 E/GeckoSessionTestRule( 3146): after performTestEndCheck
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  03-08 19:45:52.347 D/EGL_emulation( 3146): eglMakeCurrent: 0x7966a27ae9e0: ver 3 0 (tinfo 0x7966a27c0900)
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  03-08 19:45:52.347 D/GeckoViewContent( 3146): handleEvent: DOMWindowClose
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  03-08 19:45:52.348 D/GeckoViewConsole( 3146): onEvent GeckoView:WebExtension:List null
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  03-08 19:45:52.349 D/GeckoViewScrollDelegate[C](20361): handleEvent: mozvisualscroll
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  03-08 19:45:52.349 E/GeckoSessionTestRule( 3146): test_end c5ee677f-bc83-49bd-9e28-2d35f3d0f059 delete[#textarea](org.mozilla.geckoview.test.SelectionActionDelegateTest)
[task 2021-03-08T19:45:52.777Z] 19:45:52  WARNING -  TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.SelectionActionDelegateTest.delete[#textarea] | java.lang.AssertionError: Changed content should match
[task 2021-03-08T19:45:52.777Z] 19:45:52     INFO -  TEST-INFO took 575ms```

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

Summary: Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest.delete[#textarea] | java.lang.AssertionError: Changed content should match → Intermittent org.mozilla.geckoview.test.SelectionActionDelegateTest#delete[#textarea] | java.lang.AssertionError: Changed content should match
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.