Closed Bug 1666400 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-NOTRUN | /pointerlock/mouse_buttons_back_forward.html | Tests that when pointer is locked, the mouseup is preventable. - expected PASS

Categories

(Core :: DOM: UI Events & Focus Handling, defect, P5)

Product:
Core
Component:
DOM: UI Events & Focus Handling
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
83 Branch
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox81 --- unaffected
firefox82 --- unaffected
firefox83 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: edgar)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1666400 - Improve robustness of wpt /pointerlock/mouse_buttons_back_forward.html test; r?smaug
47 bytes, text/x-phabricator-request
Details | Review

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=316307800&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RBrBPX8SQ2GgkH-wVfloZg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-09-21T22:30:29.481Z] 22:30:29 INFO - TEST-OK | /pointerlock/idlharness.window.html | took 2844ms
[task 2020-09-21T22:30:29.482Z] 22:30:29 INFO - TEST-START | /pointerlock/mouse_buttons_back_forward.html
[task 2020-09-21T22:30:29.526Z] 22:30:29 INFO - Closing window 6442450947
[task 2020-09-21T22:30:30.939Z] 22:30:30 INFO - {'actions': [{u'type': u'none', u'id': u'0', u'actions': [{u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}]}, {u'type': u'pointer', u'actions': [{u'y': 1, u'x': 1, u'type': u'pointerMove', u'origin': u'viewport'}, {u'button': 0, u'type': u'pointerDown'}, {u'button': 0, u'type': u'pointerUp'}, {u'button': 3, u'type': u'pointerDown'}, {u'button': 3, u'type': u'pointerUp'}, {u'button': 4, u'type': u'pointerDown'}, {u'button': 4, u'type': u'pointerUp'}], u'parameters': {u'pointerType': u'mouse'}, u'id': u'1'}]}
[task 2020-09-21T22:30:31.056Z] 22:30:31 INFO - PID 2290 | [Child 2546, Main Thread] ###!!! ASSERTION: Button not known.: 'Error', file /builds/worker/checkouts/gecko/dom/base/nsContentUtils.cpp, line 7800
[task 2020-09-21T22:30:31.224Z] 22:30:31 INFO - PID 2290 | [Child 2546, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-09-21T22:30:31.224Z] 22:30:31 INFO - PID 2290 | [Child 2546, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 348
[task 2020-09-21T22:31:00.738Z] 22:31:00 INFO - Got content assert count 1
[task 2020-09-21T22:31:00.738Z] 22:31:00 INFO -
[task 2020-09-21T22:31:00.738Z] 22:31:00 INFO - TEST-UNEXPECTED-NOTRUN | /pointerlock/mouse_buttons_back_forward.html | Tests that when pointer is locked, the mouseup is preventable. - expected PASS
[task 2020-09-21T22:31:00.739Z] 22:31:00 INFO - TEST-KNOWN-FAIL | /pointerlock/mouse_buttons_back_forward.html | assertion count 1 matches expected 0 to 2 assertions
[task 2020-09-21T22:31:00.739Z] 22:31:00 INFO - TEST-UNEXPECTED-TIMEOUT | /pointerlock/mouse_buttons_back_forward.html | expected OK
[task 2020-09-21T22:31:00.739Z] 22:31:00 INFO - TEST-INFO took 31263ms
[task 2020-09-21T22:31:00.760Z] 22:31:00 INFO - PID 2290 | 1600727460755 Marionette INFO Stopped listening on port 60326
[task 2020-09-21T22:31:00.943Z] 22:31:00 INFO - PID 2290 | [Parent 2290, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp, line 370
[task 2020-09-21T22:31:00.987Z] 22:31:00 INFO - PID 2290 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-21T22:31:01.012Z] 22:31:01 INFO - PID 2290 | [Child 2546, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp, line 511
[task 2020-09-21T22:31:01.114Z] 22:31:01 INFO - PID 2290 | [2020-09-21T22:31:01Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-09-21T22:31:01.115Z] 22:31:01 INFO - PID 2290 | [2020-09-21T22:31:01Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-09-21T22:31:01.182Z] 22:31:01 INFO - PID 2290 | [Child 2503, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:01.202Z] 22:31:01 INFO - PID 2290 | [Child 2503, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:01.222Z] 22:31:01 INFO - PID 2290 | [Child 2546, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:01.238Z] 22:31:01 INFO - PID 2290 | [Child 2546, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:01.495Z] 22:31:01 INFO - PID 2290 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-21T22:31:01.495Z] 22:31:01 INFO - PID 2290 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-21T22:31:01.497Z] 22:31:01 INFO - PID 2290 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-21T22:31:01.537Z] 22:31:01 INFO - PID 2290 | [Child 2556, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:01.545Z] 22:31:01 INFO - PID 2290 | [Child 2724, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:01.545Z] 22:31:01 INFO - PID 2290 | [Child 2561, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:01.561Z] 22:31:01 INFO - PID 2290 | [Child 2556, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:01.561Z] 22:31:01 INFO - PID 2290 | [Child 2724, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:01.565Z] 22:31:01 INFO - PID 2290 | [Child 2561, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:01.726Z] 22:31:01 INFO - PID 2290 | [Parent 2290, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1276
[task 2020-09-21T22:31:02.609Z] 22:31:02 INFO - PID 2290 | [Parent 2290, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:02.650Z] 22:31:02 INFO - PID 2290 | [Parent 2290, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:02.706Z] 22:31:02 INFO - Browser exited with return code 0
[task 2020-09-21T22:31:02.706Z] 22:31:02 INFO - PROCESS LEAKS /tmp/tmpgchDcD/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.706Z] 22:31:02 INFO - leakcheck | Processing log file /tmp/tmpgchDcD/runtests_leaks_1114.log for scope /pointerlock
[task 2020-09-21T22:31:02.706Z] 22:31:02 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2020-09-21T22:31:02.706Z] 22:31:02 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-09-21T22:31:02.707Z] 22:31:02 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - leakcheck | Processing leak log file /tmp/tmpgchDcD/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO -
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 2290
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO -
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-09-21T22:31:02.708Z] 22:31:02 INFO - 0 |TOTAL | 40 0| 1465170 0|
[task 2020-09-21T22:31:02.715Z] 22:31:02 INFO -
[task 2020-09-21T22:31:02.716Z] 22:31:02 INFO - nsTraceRefcnt::DumpStatistics: 2000 entries
[task 2020-09-21T22:31:02.716Z] 22:31:02 INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2020-09-21T22:31:02.716Z] 22:31:02 INFO - Closing logging queue
[task 2020-09-21T22:31:02.716Z] 22:31:02 INFO - queue closed
[task 2020-09-21T22:31:02.732Z] 22:31:02 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmppCBmED
[task 2020-09-21T22:31:02.741Z] 22:31:02 INFO - PID 2666 | [2666, Unnamed thread 7f2574a57b80] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [2666, Unnamed thread 7f2574a57b80] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [2666, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:25Z WARN rkv::backend::impl_safe::environment] load_ratio() is irrelevant for this storage backend.
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [2666, Main Thread] WARNING: GLX_swap_control unsupported, ASAP mode may still block on buffer swaps.: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderGLX.cpp, line 217
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [2666, Main Thread] WARNING: SGI_video_sync unsupported. Falling back to software vsync.: file /builds/worker/checkouts/gecko/gfx/thebes/gfxPlatformGtk.cpp, line 712
[task 2020-09-21T22:31:02.745Z] 22:31:02 INFO - PID 2666 | [GLX] window 200003 has VisualID 0x41
[task 2020-09-21T22:31:02.746Z] 22:31:02 INFO - PID 2666 | [2666, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp, line 628
[task 2020-09-21T22:31:02.746Z] 22:31:02 INFO - PID 2666 | [2666, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp, line 966
[task 2020-09-21T22:31:02.746Z] 22:31:02 INFO - PID 2666 | [2666, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp, line 628
[task 2020-09-21T22:31:02.746Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:26Z WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2020-09-21T22:31:02.746Z] 22:31:02 INFO - PID 2666 | [2666, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4276
[task 2020-09-21T22:31:02.747Z] 22:31:02 INFO - PID 2666 | [2666, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4274
[task 2020-09-21T22:31:02.747Z] 22:31:02 INFO - PID 2666 | [Parent 2666, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp, line 1564
[task 2020-09-21T22:31:02.747Z] 22:31:02 INFO - PID 2666 | LoadPlugin() /usr/lib/mozilla/plugins/libgnome-shell-browser-plugin.so returned 7f25583b2220
[task 2020-09-21T22:31:02.748Z] 22:31:02 INFO - PID 2666 | [2878, Unnamed thread 7f92b135d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.749Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.749Z] 22:31:02 INFO - PID 2666 | [2878, Unnamed thread 7f92b135d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.749Z] 22:31:02 INFO - PID 2666 | [2878, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.749Z] 22:31:02 INFO - PID 2666 | [Child 2878, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:02.750Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp, line 1086
[task 2020-09-21T22:31:02.750Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp, line 1086
[task 2020-09-21T22:31:02.750Z] 22:31:02 INFO - PID 2666 | [2903, Unnamed thread 7fee1a55d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.750Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.751Z] 22:31:02 INFO - PID 2666 | [2903, Unnamed thread 7fee1a55d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.751Z] 22:31:02 INFO - PID 2666 | [2903, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.751Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp, line 1086
[task 2020-09-21T22:31:02.751Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp, line 1086
[task 2020-09-21T22:31:02.752Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4274
[task 2020-09-21T22:31:02.757Z] 22:31:02 INFO - PID 2666 | [Child 2903, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:02.757Z] 22:31:02 INFO - PID 2666 | [Child 2903, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp, line 2686
[task 2020-09-21T22:31:02.757Z] 22:31:02 INFO - PID 2666 | [Child 2903, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp, line 2686
[task 2020-09-21T22:31:02.758Z] 22:31:02 INFO - PID 2666 | [Child 2903, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp, line 2686
[task 2020-09-21T22:31:02.758Z] 22:31:02 INFO - PID 2666 | [Child 2903, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp, line 2686
[task 2020-09-21T22:31:02.759Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:29Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 8, found at -1
[task 2020-09-21T22:31:02.759Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:29Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 10, found at -1
[task 2020-09-21T22:31:02.759Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:29Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 12, found at -1
[task 2020-09-21T22:31:02.759Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:29Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 14, found at -1
[task 2020-09-21T22:31:02.760Z] 22:31:02 INFO - PID 2666 | [2943, Unnamed thread 7ff0c375d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.760Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.761Z] 22:31:02 INFO - PID 2666 | [2943, Unnamed thread 7ff0c375d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.761Z] 22:31:02 INFO - PID 2666 | [2943, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.762Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: '!mLastFocusedWindow', file /builds/worker/checkouts/gecko/widget/gtk/IMContextWrapper.cpp, line 3097
[task 2020-09-21T22:31:02.762Z] 22:31:02 INFO - PID 2666 | [2950, Unnamed thread 7f9f4f15d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.763Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.763Z] 22:31:02 INFO - PID 2666 | [2950, Unnamed thread 7f9f4f15d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.764Z] 22:31:02 INFO - PID 2666 | [2950, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.765Z] 22:31:02 INFO - PID 2666 | [Child 2943, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:02.765Z] 22:31:02 INFO - PID 2666 | [Child 2950, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:02.766Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 8, found at -1
[task 2020-09-21T22:31:02.767Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 10, found at -1
[task 2020-09-21T22:31:02.767Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 11, found at -1
[task 2020-09-21T22:31:02.768Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 12, found at -1
[task 2020-09-21T22:31:02.768Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 13, found at -1
[task 2020-09-21T22:31:02.769Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 14, found at -1
[task 2020-09-21T22:31:02.770Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:30Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 15, found at -1
[task 2020-09-21T22:31:02.770Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:32Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2020-09-21T22:31:02.771Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:32Z WARN webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2020-09-21T22:31:02.771Z] 22:31:02 INFO - PID 2666 | [GLX] window 200017 has VisualID 0x41
[task 2020-09-21T22:31:02.771Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp, line 628
[task 2020-09-21T22:31:02.771Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp, line 966
[task 2020-09-21T22:31:02.772Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp, line 628
[task 2020-09-21T22:31:02.772Z] 22:31:02 INFO - PID 2666 | [2020-09-21T22:30:32Z WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2020-09-21T22:31:02.772Z] 22:31:02 INFO - PID 2666 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2020-09-21T22:31:02.773Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4276
[task 2020-09-21T22:31:02.773Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp, line 1132
[task 2020-09-21T22:31:02.773Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x7f253989ed90 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp, line 113
[task 2020-09-21T22:31:02.773Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp, line 402
[task 2020-09-21T22:31:02.774Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp, line 1132
[task 2020-09-21T22:31:02.774Z] 22:31:02 INFO - PID 2666 | [3008, Unnamed thread 7f041b75d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.774Z] 22:31:02 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:02.775Z] 22:31:02 INFO - PID 2666 | [3008, Unnamed thread 7f041b75d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:02.775Z] 22:31:02 INFO - PID 2666 | [3008, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:02.775Z] 22:31:02 INFO - PID 2666 | [Child 3008, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:02.775Z] 22:31:02 INFO - PID 2666 | 1600727435607 Marionette INFO Listening on port 44195
[task 2020-09-21T22:31:02.775Z] 22:31:02 INFO - Starting runner
[task 2020-09-21T22:31:02.938Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4274
[task 2020-09-21T22:31:02.939Z] 22:31:02 INFO - PID 2666 | [Parent 2666, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4274
[task 2020-09-21T22:31:03.075Z] 22:31:03 INFO - PID 2666 | [Child 2943, Main Thread] WARNING: NS_ENSURE_TRUE(browserChrome) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp, line 12028
[task 2020-09-21T22:31:03.115Z] 22:31:03 INFO - PID 2666 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-09-21T22:31:03.151Z] 22:31:03 INFO - PID 2666 | [3099, Unnamed thread 7f7019a5d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:03.153Z] 22:31:03 INFO - PID 2666 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpl17YXA/runtests_leaks_1114.log
[task 2020-09-21T22:31:03.153Z] 22:31:03 INFO - PID 2666 | [3099, Unnamed thread 7f7019a5d5e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-09-21T22:31:03.153Z] 22:31:03 INFO - PID 2666 | [3099, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp, line 246
[task 2020-09-21T22:31:03.249Z] 22:31:03 INFO - PID 2666 | [Child 3099, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp, line 626
[task 2020-09-21T22:31:03.285Z] 22:31:03 INFO - PID 2666 | [Child 2878, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-21T22:31:03.313Z] 22:31:03 INFO - PID 2666 | [Child 2878, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-21T22:31:03.605Z] 22:31:03 INFO - TEST-START | /pointerlock/movementX_Y_basic.html

(In reply to Sebastian Hengst [:aryx] (needinfo on intermittent or backout) from comment #1)

Regression from bug 1662587?

it seems so, I could reproduce locally under verify mode, requesting pointer lock now sends IPC to the parent process and waits for the parent process response. Sometimes, the synthesized mouse event comes before the IPC response. I guess we could make the test more robust a bit.

Assignee: nobody → echen
Flags: needinfo?(echen)
Has Regression Range: --- → yes
Keywords: regression
Component: DOM: Events → DOM: UI Events & Focus Handling
Pushed by echen@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/36ec61276059 Improve robustness of wpt /pointerlock/mouse_buttons_back_forward.html test; r=smaug
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/25759 for changes under testing/web-platform/tests

Set release status flags based on info from the regressing bug 1662587

status-firefox81: --- → unaffected
status-firefox82: --- → unaffected
status-firefox83: --- → affected
status-firefox-esr78: --- → unaffected

https://hg.mozilla.org/mozilla-central/rev/36ec61276059

Status: NEW → RESOLVED
Closed: 4 years ago
status-firefox83: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 83 Branch
Upstream PR merged by moz-wptsync-bot
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: