1518883 - Intermittent browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | Expected only extension menu items - ["context-openlinkintab","context-openlinkinusercontext-menu","context-openlink"

Status: RESOLVED FIXED

(WebExtensions :: Untriaged, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: cbrindusan [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=220874572&repo=autoland

https://queue.taskcluster.net/v1/task/E_xYTV8QQguqKsQShgPs6A/runs/0/artifacts/public/logs/live_backing.log

[task 2019-01-09T18:16:54.270Z] 18:16:54 INFO - TEST-START | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js
[task 2019-01-09T18:16:54.270Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xdc097800 == 6 [pid = 2296] [id = {b2706996-497e-4f64-a012-3d453c30a770}]
[task 2019-01-09T18:16:54.271Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 15 (0xe1a061c0) [pid = 2296] [serial = 15] [outer = (nil)]
[task 2019-01-09T18:16:54.271Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 16 (0xdd890400) [pid = 2296] [serial = 16] [outer = 0xe1a061c0]
[task 2019-01-09T18:16:54.271Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 2 (0xe79bd400) [pid = 2491] [serial = 2] [outer = 0xf716dd90]
[task 2019-01-09T18:16:54.271Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: '!mSelection', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 4823
[task 2019-01-09T18:16:54.272Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: '!editActionData.CanHandle()', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 1250
[task 2019-01-09T18:16:54.273Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 17 (0xdd895000) [pid = 2296] [serial = 17] [outer = 0xe1a061c0]
[task 2019-01-09T18:16:54.273Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, StreamTrans #41] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
[task 2019-01-09T18:16:54.273Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
[task 2019-01-09T18:16:54.274Z] 18:16:54 INFO - GECKO(2296) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpQtl_sD.mozrunner/runtests_leaks_tab_pid2517.log
[task 2019-01-09T18:16:54.274Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xe0ad2c00 == 7 [pid = 2296] [id = {044be8a9-efb1-4814-8e29-1dc181c3edc2}]
[task 2019-01-09T18:16:54.275Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 18 (0xe1a06420) [pid = 2296] [serial = 18] [outer = (nil)]
[task 2019-01-09T18:16:54.275Z] 18:16:54 INFO - GECKO(2296) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-01-09T18:16:54.276Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsContentUtils.cpp, line 3748
[task 2019-01-09T18:16:54.277Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Error loading theme icon 'edit-clear' for stock: : 'glib warning', file /builds/worker/workspace/build/src/toolkit/xre/nsSigHandlers.cpp, line 135
[task 2019-01-09T18:16:54.277Z] 18:16:54 INFO - GECKO(2296) | (firefox:2296): Gtk-WARNING **: Error loading theme icon 'edit-clear' for stock:
[task 2019-01-09T18:16:54.277Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 23 (0xe7bb8400) [pid = 2370] [serial = 27] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.278Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 22 (0xe739a000) [pid = 2370] [serial = 24] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.279Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 21 (0xe789e000) [pid = 2370] [serial = 25] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.280Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 20 (0xe7897800) [pid = 2370] [serial = 19] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.281Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 19 (0xe7397800) [pid = 2370] [serial = 20] [outer = (nil)] [url = moz-extension://e58a43ab-c4c5-42cf-9ac5-d8fe765b5b25/sidebar.html]
[task 2019-01-09T18:16:54.281Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 18 (0xe84c9000) [pid = 2370] [serial = 22] [outer = (nil)] [url = http://mochi.test:8888/?sidebar]
[task 2019-01-09T18:16:54.282Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 17 (0xe7392000) [pid = 2370] [serial = 14] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.282Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 16 (0xe7393400) [pid = 2370] [serial = 15] [outer = (nil)] [url = moz-extension://598790fe-dada-478c-b246-4102c533e8b9/_generated_background_page.html]
[task 2019-01-09T18:16:54.284Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 15 (0xe7396000) [pid = 2370] [serial = 17] [outer = (nil)] [url = http://mochi.test:8888/?background]
[task 2019-01-09T18:16:54.285Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 14 (0xe7898800) [pid = 2370] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.286Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 13 (0xe78a1c00) [pid = 2370] [serial = 12] [outer = (nil)] [url = moz-extension://da3bc560-b896-4acc-9613-3cc60ad8302f/_generated_background_page.html]
[task 2019-01-09T18:16:54.286Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 12 (0xe7bc0800) [pid = 2370] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.287Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 11 (0xf7197000) [pid = 2370] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.287Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 10 (0xe7897c00) [pid = 2370] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.288Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 19 (0xe3ea0c00) [pid = 2296] [serial = 19] [outer = 0xe1a06420]
[task 2019-01-09T18:16:54.288Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
[task 2019-01-09T18:16:54.289Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
[task 2019-01-09T18:16:54.289Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
[task 2019-01-09T18:16:54.290Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 20 (0xdecb6400) [pid = 2296] [serial = 20] [outer = 0xe1a06420]
[task 2019-01-09T18:16:54.291Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 3 (0xe7bb8800) [pid = 2389] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.291Z] 18:16:54 INFO - GECKO(2296) | --DOCSHELL 0xe7769400 == 0 [pid = 2389] [id = {1be72dd9-abc1-4202-b65b-bb04bf3e6a4d}]
[task 2019-01-09T18:16:54.292Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 2 (0xf706dd90) [pid = 2389] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.293Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/protocol/res/SubstitutingProtocolHandler.cpp, line 318
[task 2019-01-09T18:16:54.294Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Need TabChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1083
[task 2019-01-09T18:16:54.295Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xe7392000 == 7 [pid = 2370] [id = {fe522f10-fe03-488d-aa90-0ae62aa1ab1b}]
[task 2019-01-09T18:16:54.295Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 11 (0xf716f120) [pid = 2370] [serial = 32] [outer = (nil)]
[task 2019-01-09T18:16:54.296Z] 18:16:54 INFO - GECKO(2296) | [Child 2370, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/TabChild.cpp, line 2564
[task 2019-01-09T18:16:54.297Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 12 (0xe7395800) [pid = 2370] [serial = 33] [outer = 0xf716f120]
[task 2019-01-09T18:16:54.298Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 13 (0xe789b400) [pid = 2370] [serial = 34] [outer = 0xf716f120]
[task 2019-01-09T18:16:54.299Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1497
[task 2019-01-09T18:16:54.299Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xe789c800 == 8 [pid = 2370] [id = {ccc57e73-5e2f-4d0d-8270-8ffceb335a71}]
[task 2019-01-09T18:16:54.300Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 14 (0xf716f970) [pid = 2370] [serial = 35] [outer = (nil)]
[task 2019-01-09T18:16:54.300Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 15 (0xe78a1c00) [pid = 2370] [serial = 36] [outer = 0xf716f970]
[task 2019-01-09T18:16:54.301Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 16 (0xe7bb8000) [pid = 2370] [serial = 37] [outer = 0xf716f970]
[task 2019-01-09T18:16:54.302Z] 18:16:54 INFO - GECKO(2296) | --DOCSHELL 0xe7390000 == 7 [pid = 2370] [id = {8d803197-b0a0-4f4b-a43e-25c6f816fd1d}]
[task 2019-01-09T18:16:54.304Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 15 (0xf71707b0) [pid = 2370] [serial = 29] [outer = (nil)] [url = http://mochi.test:8888/?browser_action_popup]
[task 2019-01-09T18:16:54.304Z] 18:16:54 INFO - GECKO(2296) | --DOCSHELL 0xe7395400 == 6 [pid = 2370] [id = {0c3ece84-f551-43c6-bb65-33290adddcb3}]
[task 2019-01-09T18:16:54.306Z] 18:16:54 INFO - GECKO(2296) | --DOCSHELL 0xe84c4000 == 5 [pid = 2370] [id = {47cc7437-0915-4138-a529-67aa823221b7}]
[task 2019-01-09T18:16:54.306Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 14 (0xf7170420) [pid = 2370] [serial = 23] [outer = (nil)] [url = moz-extension://5bcc961d-6c3d-4874-87a7-977e8f53145c/popup.html]
[task 2019-01-09T18:16:54.307Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1198
[task 2019-01-09T18:16:54.308Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1198
[task 2019-01-09T18:16:54.308Z] 18:16:54 INFO - GECKO(2296) | [Child 2370, Main Thread] WARNING: NS_ENSURE_TRUE(mDoneSetup) failed: file /builds/worker/workspace/build/src/editor/composer/nsEditingSession.cpp, line 1209
[task 2019-01-09T18:16:54.309Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xdec88800 == 8 [pid = 2296] [id = {3d3be840-ddea-4a6f-9d7b-5a990a8c3026}]
[task 2019-01-09T18:16:54.309Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 21 (0xe66e0a10) [pid = 2296] [serial = 21] [outer = (nil)]
[task 2019-01-09T18:16:54.310Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 22 (0xe0a2a000) [pid = 2296] [serial = 22] [outer = 0xe66e0a10]
[task 2019-01-09T18:16:54.310Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, QuotaManager IO] WARNING: 'NS_FAILED(directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7108
[task 2019-01-09T18:16:54.310Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, QuotaManager IO] WARNING: 'NS_FAILED(directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7108
[task 2019-01-09T18:16:54.311Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, QuotaManager IO] WARNING: 'NS_FAILED(directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7108
[task 2019-01-09T18:16:54.311Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, QuotaManager IO] WARNING: 'NS_FAILED(directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 7108
[task 2019-01-09T18:16:54.313Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 23 (0xdecb7400) [pid = 2296] [serial = 23] [outer = 0xe66e0a10]
[task 2019-01-09T18:16:54.314Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 2 (0xe78bc800) [pid = 2466] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.316Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 1 (0xe77ca000) [pid = 2389] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.317Z] 18:16:54 INFO - GECKO(2296) | --DOMWINDOW == 0 (0xe7bb7c00) [pid = 2389] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2019-01-09T18:16:54.319Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xdcfe1c00 == 9 [pid = 2296] [id = {a16b4122-cb53-420f-9381-b1d0ed4435ac}]
[task 2019-01-09T18:16:54.320Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 24 (0xe66e0c70) [pid = 2296] [serial = 24] [outer = (nil)]
[task 2019-01-09T18:16:54.321Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsContentUtils.cpp, line 3748
[task 2019-01-09T18:16:54.323Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 25 (0xe5d2d800) [pid = 2296] [serial = 25] [outer = 0xe66e0c70]
[task 2019-01-09T18:16:54.324Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1497
[task 2019-01-09T18:16:54.326Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/protocol/res/SubstitutingProtocolHandler.cpp, line 318
[task 2019-01-09T18:16:54.328Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Need TabChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1083
[task 2019-01-09T18:16:54.329Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xe7399800 == 6 [pid = 2370] [id = {cf9bc9a7-d5a4-493a-a122-b51b266cd56d}]
[task 2019-01-09T18:16:54.330Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 15 (0xf716fd00) [pid = 2370] [serial = 38] [outer = (nil)]
[task 2019-01-09T18:16:54.332Z] 18:16:54 INFO - GECKO(2296) | [Child 2370, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/TabChild.cpp, line 2564
[task 2019-01-09T18:16:54.333Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 16 (0xe789bc00) [pid = 2370] [serial = 39] [outer = 0xf716fd00]
[task 2019-01-09T18:16:54.334Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 17 (0xe78a0c00) [pid = 2370] [serial = 40] [outer = 0xf716fd00]
[task 2019-01-09T18:16:54.336Z] 18:16:54 INFO - GECKO(2296) | ++DOCSHELL 0xe7bbd000 == 7 [pid = 2370] [id = {387ef727-6d34-4ca2-8ba9-57a04ed29603}]
[task 2019-01-09T18:16:54.340Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 18 (0xf7170680) [pid = 2370] [serial = 41] [outer = (nil)]
[task 2019-01-09T18:16:54.341Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 19 (0xe7bc0800) [pid = 2370] [serial = 42] [outer = 0xf7170680]
[task 2019-01-09T18:16:54.342Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1497
[task 2019-01-09T18:16:54.344Z] 18:16:54 INFO - GECKO(2296) | ++DOMWINDOW == 20 (0xe84c5000) [pid = 2370] [serial = 43] [outer = 0xf7170680]
[task 2019-01-09T18:16:54.345Z] 18:16:54 INFO - GECKO(2296) | JavaScript error: resource:///actors/ContextMenuChild.jsm, line 478: TypeError: doc is null
[task 2019-01-09T18:16:54.347Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, StreamTrans #49] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
[task 2019-01-09T18:16:54.348Z] 18:16:54 INFO - GECKO(2296) | [Parent 2296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
[task 2019-01-09T18:16:54.349Z] 18:16:54 INFO - TEST-INFO | started process screentopng
[task 2019-01-09T18:16:54.840Z] 18:16:54 INFO - TEST-INFO | screentopng: exit 0
[task 2019-01-09T18:16:54.842Z] 18:16:54 INFO - Buffered messages logged at 18:15:16
[task 2019-01-09T18:16:54.843Z] 18:16:54 INFO - Entering test bound test_tab_options_popups
[task 2019-01-09T18:16:54.846Z] 18:16:54 INFO - Extension loaded
[task 2019-01-09T18:16:54.848Z] 18:16:54 INFO - Buffered messages logged at 18:15:22
[task 2019-01-09T18:16:54.850Z] 18:16:54 INFO - Global property added while loading chrome://browser/content/nsContextMenu.js: gContextMenuContentData
[task 2019-01-09T18:16:54.852Z] 18:16:54 INFO - Global property added while loading chrome://browser/content/nsContextMenu.js: SpellCheckHelper
[task 2019-01-09T18:16:54.854Z] 18:16:54 INFO - Global property added while loading chrome://browser/content/nsContextMenu.js: LoginHelper
[task 2019-01-09T18:16:54.855Z] 18:16:54 INFO - Global property added while loading chrome://browser/content/nsContextMenu.js: LoginManagerContextMenu
[task 2019-01-09T18:16:54.856Z] 18:16:54 INFO - Global property added while loading chrome://browser/content/nsContextMenu.js: DevToolsShim
[task 2019-01-09T18:16:54.860Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkintab should not be hidden -
[task 2019-01-09T18:16:54.862Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkintab should not be disabled -
[task 2019-01-09T18:16:54.863Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkprivate should not be hidden -
[task 2019-01-09T18:16:54.865Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkprivate should not be disabled -
[task 2019-01-09T18:16:54.866Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-copylink should not be hidden -
[task 2019-01-09T18:16:54.868Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-copylink should not be disabled -
[task 2019-01-09T18:16:54.869Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkinusercontext-menu should not be hidden -
[task 2019-01-09T18:16:54.871Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | context-openlinkinusercontext-menu should not be disabled -
[task 2019-01-09T18:16:54.880Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | Got a targetElementId in the menu details -
[task 2019-01-09T18:16:54.882Z] 18:16:54 INFO - TEST-PASS | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | Got the expected menu details from menus.onShown - {"menuIds":["anypage"],"contexts":["link","all"],"viewType":"undefined","frameId":0,"editable":false,"linkText":"options page link","linkUrl":"http://mochi.test:8888/","pageUrl":"moz-extension://4b10e735-03d2-4634-83e3-959977bd6646/options.html"} deepEqual {"menuIds":["anypage"],"contexts":["link","all"],"viewType":"undefined","frameId":0,"editable":false,"linkText":"options page link","linkUrl":"http://mochi.test:8888/","pageUrl":"moz-extension://4b10e735-03d2-4634-83e3-959977bd6646/options.html"} -
[task 2019-01-09T18:16:54.883Z] 18:16:54 INFO - Leaving test bound test_tab_options_popups
[task 2019-01-09T18:16:54.884Z] 18:16:54 INFO - Entering test bound overrideContext_in_options_page
[task 2019-01-09T18:16:54.886Z] 18:16:54 INFO - Buffered messages logged at 18:15:23
[task 2019-01-09T18:16:54.887Z] 18:16:54 INFO - Extension loaded
[task 2019-01-09T18:16:54.888Z] 18:16:54 INFO - Buffered messages logged at 18:15:26
[task 2019-01-09T18:16:54.890Z] 18:16:54 INFO - Console message: [JavaScript Error: "TypeError: doc is null" {file: "resource:///actors/ContextMenuChild.jsm" line: 478}]
[task 2019-01-09T18:16:54.891Z] 18:16:54 INFO - Buffered messages finished
[task 2019-01-09T18:16:54.892Z] 18:16:54 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js | Expected only extension menu items - ["context-openlinkintab","context-openlinkinusercontext-menu","context-openlink","context-openlinkprivate","context-sep-open","context-bookmarklink","context-savelink","context-savelinktopocket","context-copylink","context-searchselect","context-sep-sendlinktodevice","context-sendlinktodevice","","5c0c1fa470041f957a883c14709b947b98703dd8_temporary-addon-menuitem-2"] deepEqual ["5c0c1fa470041f957a883c14709b947b98703dd8_temporary-addon-menuitem-_bg_1_1","5c0c1fa470041f957a883c14709b947b98703dd8_temporary-addon-menuitem-_bg_1_2"] - JS frame :: chrome://mochitests/content/browser/browser/components/extensions/test/browser/test-oop-extensions/browser_ext_optionsPage_popups.js :: overrideContext_in_options_page :: line 186

Comment 1

[Cosmin Sabou [:CosminS]]

This test was introduced with Bug 1468460: https://hg.mozilla.org/integration/autoland/rev/d3f022310ff3c5864f62f979e85c7ff0a27de82b

Th: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Csuccess%2Crunning%2Cpending%2Crunnable&searchStr=linux%2Cdebug%2Cmochitests%2Cwith%2Ce10s%2Ctest-linux32%2Fdebug-mochitest-browser-chrome-e10s-2%2Cm-e10s%28bc2%29&tochange=5345e8472f29cd844c8218b50051c01f8aa12aee&group_state=expanded&fromchange=cf9a58cbb9040639fb3f83e2f7b70e6f4b4518e8

Luca, could you please take a look over these failures?
There are 23 failures in two days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-01-04&endday=2019-01-11&tree=trunk&bug=1518883 and they mostly fail on linux32 debug. Thanks.

Comment 2

[Intermittent Failures Robot]

18 failures in 495 pushes (0.036 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-inbound: 5
• autoland: 11
• mozilla-central: 2

Platform breakdown:

• linux32: 16
• linux64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1518883&startday=2019-01-10&endday=2019-01-10&tree=all

Comment 3

[Luca Greco [:rpl] [:luca] [:lgreco]]

Attachment: Bug 1518883 - Fix browser_ext_optionsPage_popups.js high intermittency on linux32-debug. r?mixedpuppy!

This patch applies the following small change to the openContextMenuInOptionsPage test helper:

Instead of using BrowserTestUtils.synthesizeMouseAtCenter, the new version of the test helper
triggers a contextmenu event directly on the target element, to prevent intermittent failures
on debug builds (especially linux32-debug).

The intermittent failures seem to be originated by an unexpected behavior of DOMWindowUtils's
sendMouseEvent method, which is randomly triggering mouse events targeting the entire HTML document
instead of the intended DOMElement (despites the EventUtils.js "synthesizeMouseAtPoint" method is
computing the same `left` and `top` position in both the cases).

It seems that there could be some timing reasons behind the issue, because the intermittency
is reduced by adding an arbitrary long delay before triggering the mouse event.

Comment 4

[Intermittent Failures Robot]

43 failures in 2627 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #24 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 27
• mozilla-inbound: 7
• try: 1
• mozilla-central: 8

Platform breakdown:

• linux32: 33
• osx-10-10: 5
• linux64-ccov: 2
• linux64: 2
• windows10-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1518883&startday=2019-01-07&endday=2019-01-13&tree=all

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/5fd809e7035d
Fix browser_ext_optionsPage_popups.js high intermittency on linux32-debug. r=mixedpuppy

Comment 6

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/5fd809e7035d

Comment 7

[Vlad Jiman]

Will manual validation be required for this bug? if so please specify some steps to correctly test the fix.

Comment 8

[Luca Greco [:rpl] [:luca] [:lgreco]]

Setting qe-verify-, as this is a test-only change.