Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=337363187&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fwabVb1kTV6Y1uBymQTA5w/runs/0/artifacts/public/logs/live_backing.log
task 2021-04-21T22:19:14.779Z] 22:19:14 INFO - TEST-OK | /pointerevents/pointerlock/pointerevent_pointermove_on_chorded_mouse_button_when_locked.html | took 1157ms
[task 2021-04-21T22:19:14.779Z] 22:19:14 INFO - TEST-START | /pointerevents/pointerlock/pointerevent_pointerrawupdate_in_pointerlock.html
[task 2021-04-21T22:19:14.792Z] 22:19:14 INFO - Closing window 6442450958
[task 2021-04-21T22:19:15.598Z] 22:19:15 INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '0'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '1c7ded27-61c4-4889-9db1-7e5b34a63a10', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '1c7ded27-61c4-4889-9db1-7e5b34a63a10'}}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerUp', 'button': 0}, {'type': 'pointerMove', 'x': 10, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '1c7ded27-61c4-4889-9db1-7e5b34a63a10', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '1c7ded27-61c4-4889-9db1-7e5b34a63a10'}}, {'type': 'pointerMove', 'x': 703, 'y': 321, 'origin': 'viewport'}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerUp', 'button': 0}, {'type': 'pointerMove', 'x': 713, 'y': 321, 'origin': 'viewport'}], 'parameters': {'pointerType': 'mouse'}, 'id': '1'}]}
[task 2021-04-21T22:19:15.715Z] 22:19:15 INFO - PID 21111 | [Child 21380, Main Thread] WARNING: Constructing RangeBoundary with invalid value: 'mRef || aOffset == 0', file /builds/worker/workspace/obj-build/dist/include/mozilla/RangeBoundary.h:85
[task 2021-04-21T22:19:16.068Z] 22:19:16 INFO - PID 21111 | [Child 21380, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-04-21T22:19:16.068Z] 22:19:16 INFO - PID 21111 | [Child 21380, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-04-21T22:19:45.425Z] 22:19:45 INFO -
[task 2021-04-21T22:19:45.425Z] 22:19:45 INFO - TEST-UNEXPECTED-NOTRUN | /pointerevents/pointerlock/pointerevent_pointerrawupdate_in_pointerlock.html | pointerrawupdate event received - expected FAIL
[task 2021-04-21T22:19:45.425Z] 22:19:45 INFO - TEST-INFO | expected FAIL
[task 2021-04-21T22:19:45.426Z] 22:19:45 INFO - TEST-UNEXPECTED-TIMEOUT | /pointerevents/pointerlock/pointerevent_pointerrawupdate_in_pointerlock.html | expected OK
[task 2021-04-21T22:19:45.426Z] 22:19:45 INFO - TEST-INFO took 30644ms
[task 2021-04-21T22:19:45.426Z] 22:19:45 INFO - Restarting browser for new test group
[task 2021-04-21T22:19:45.428Z] 22:19:45 INFO - PID 21111 | 1619043585427 Marionette INFO Stopped listening on port 43265
[task 2021-04-21T22:19:45.469Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.469Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.488Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.496Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.501Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.502Z] 22:19:45 INFO - PID 21111 | [Child 21320, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-04-21T22:19:45.578Z] 22:19:45 INFO - PID 21111 | [Parent 21111, Main Thread] WARNING: '!mIMContext', file /builds/worker/checkouts/gecko/widget/gtk/nsWindow.cpp:7568
[task 2021-04-21T22:19:45.662Z] 22:19:45 INFO - PID 21111 | [Parent 21111, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-04-21T22:19:45.681Z] 22:19:45 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:45.685Z] 22:19:45 INFO - PID 21111 | JavaScript error: resource:///actors/PointerLockParent.jsm, line 20: TypeError: can't access property "ownerGlobal", browser is null
[task 2021-04-21T22:19:45.699Z] 22:19:45 INFO - PID 21111 | [Child 21380, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:532
[task 2021-04-21T22:19:45.716Z] 22:19:45 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:45.716Z] 22:19:45 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:45.822Z] 22:19:45 INFO - PID 21111 | [2021-04-21T22:19:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2021-04-21T22:19:45.823Z] 22:19:45 INFO - PID 21111 | [2021-04-21T22:19:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2021-04-21T22:19:46.309Z] 22:19:46 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:46.310Z] 22:19:46 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:46.310Z] 22:19:46 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:46.335Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.000002s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.340Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.000685s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.341Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.000706s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.341Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.001414s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.341Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.001683s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.341Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: cache: '0.001718s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.341Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: sdb: '0.001728s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.342Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: ls: '0.001735s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.343Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.006926s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.345Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.006953s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.346Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.007027s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.347Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.007046s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.348Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.007053s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.349Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: idb: '0.007070s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.350Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: cache: '0.007103s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.351Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: sdb: '0.007109s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.352Z] 22:19:46 INFO - PID 21111 | [Parent 21111, IPDL Background] WARNING: ls: '0.007115s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3690
[task 2021-04-21T22:19:46.353Z] 22:19:46 INFO - PID 21111 | [Child 21390, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3361
[task 2021-04-21T22:19:46.354Z] 22:19:46 INFO - PID 21111 | [Child 21794, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3361
[task 2021-04-21T22:19:46.355Z] 22:19:46 INFO - PID 21111 | [Child 21794, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-04-21T22:19:46.359Z] 22:19:46 INFO - PID 21111 | [Child 21390, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-04-21T22:19:46.363Z] 22:19:46 INFO - PID 21111 | [Child 21384, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3361
[task 2021-04-21T22:19:46.365Z] 22:19:46 INFO - PID 21111 | [Child 21794, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-21T22:19:46.366Z] 22:19:46 INFO - PID 21111 | [Child 21384, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-04-21T22:19:46.376Z] 22:19:46 INFO - PID 21111 | [Child 21390, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-21T22:19:46.382Z] 22:19:46 INFO - PID 21111 | [Child 21384, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-21T22:19:46.571Z] 22:19:46 INFO - PID 21111 | [Parent 21111, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp:1279
[task 2021-04-21T22:19:46.581Z] 22:19:46 INFO - PID 21111 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-04-21T22:19:47.694Z] 22:19:47 INFO - PID 21111 | [Parent 21111, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3361
[task 2021-04-21T22:19:47.711Z] 22:19:47 INFO - PID 21111 | [Parent 21111, Main Thread] WARNING: '!obs', file /builds/worker/checkouts/gecko/toolkit/components/sessionstore/RestoreTabContentObserver.cpp:58
[task 2021-04-21T22:19:47.743Z] 22:19:47 INFO - PID 21111 | [Parent 21111, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-04-21T22:19:47.764Z] 22:19:47 INFO - Browser exited with return code 0
[task 2021-04-21T22:19:47.765Z] 22:19:47 INFO - PROCESS LEAKS /tmp/tmp78dl7al7/runtests_leaks_1149.log
[task 2021-04-21T22:19:47.765Z] 22:19:47 INFO - leakcheck | Processing log file /tmp/tmp78dl7al7/runtests_leaks_1149.log for scope /pointerevents/pointerlock
[task 2021-04-21T22:19:47.766Z] 22:19:47 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 153600 bytes
[task 2021-04-21T22:19:47.766Z] 22:19:47 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-04-21T22:19:47.767Z] 22:19:47 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-04-21T22:19:47.768Z] 22:19:47 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2021-04-21T22:19:47.768Z] 22:19:47 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-04-21T22:19:47.769Z] 22:19:47 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2021-04-21T22:19:47.769Z] 22:19:47 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-04-21T22:19:47.770Z] 22:19:47 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 307200 bytes
[task 2021-04-21T22:19:47.770Z] 22:19:47 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes```
Description
•