Closed Bug 1765747 Opened 4 years ago Closed 4 years ago

Intermittent TV-fis browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js | unexpected tabs.onUpdated during tab creation -

Categories

(Firefox :: Tabbed Browser, defect, P5)

Product:
Firefox
Component:
Tabbed Browser
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
101 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox99 --- unaffected
firefox100 --- unaffected
firefox101 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1765747 - Fix intermittent failure in browser_ext_tabs_onCreated.js. r?mixedpuppy
48 bytes, text/x-phabricator-request
Details | Review

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=375272089&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/E-3rzIVpQwufVc0YKWWWHA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/E-3rzIVpQwufVc0YKWWWHA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2022-04-21T09:00:58.657Z] 09:00:58     INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js
[task 2022-04-21T09:00:58.953Z] 09:00:58     INFO - TEST-INFO | started process screentopng
[task 2022-04-21T09:00:59.381Z] 09:00:59     INFO - TEST-INFO | screentopng: exit 0
[task 2022-04-21T09:00:59.381Z] 09:00:59     INFO - Buffered messages logged at 09:00:58
[task 2022-04-21T09:00:59.382Z] 09:00:59     INFO - Entering test bound test_onCreated_active
[task 2022-04-21T09:00:59.382Z] 09:00:59     INFO - Extension loaded
[task 2022-04-21T09:00:59.383Z] 09:00:59     INFO - TEST-PASS | browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js | Tab should be active - 
[task 2022-04-21T09:00:59.384Z] 09:00:59     INFO - Buffered messages finished
[task 2022-04-21T09:00:59.386Z] 09:00:59     INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js | unexpected tabs.onUpdated during tab creation - 
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - Stack trace:
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - chrome://mochikit/content/browser-test.js:test_ok:1394
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:testHandler:88
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:testResult:98
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://specialpowers/SpecialPowersChild.jsm:listener:2033
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://specialpowers/SpecialPowersChild.jsm:loadExtension/<:1963
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://specialpowers/SpecialPowersChild.jsm:receiveMessage:267
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - JSActor query*resource://specialpowers/SpecialPowersParent.jsm:resultListener:1118
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://gre/modules/ExtensionCommon.jsm:emit:322
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://gre/modules/Extension.jsm:receiveMessage:2391
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - MessageListener.receiveMessage*resource://gre/modules/Extension.jsm:Extension:2199
[task 2022-04-21T09:00:59.387Z] 09:00:59     INFO - resource://testing-common/ExtensionTestCommon.jsm:generate:678
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - resource://specialpowers/SpecialPowersParent.jsm:receiveMessage:1115
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - JSActor query*resource://specialpowers/SpecialPowersChild.jsm:loadExtension:2025
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension:129
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochitests/content/browser/browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js:test_onCreated_active:7
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/browser-test.js:handleTask:989
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1061
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1189
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:972
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041
[task 2022-04-21T09:00:59.388Z] 09:00:59     INFO - GECKO(7510) | JavaScript error: resource:///actors/ContentSearchParent.jsm, line 664: TypeError: can't access property "addEventListener", browser is null
[task 2022-04-21T09:00:59.389Z] 09:00:59     INFO - GECKO(7510) | JavaScript error: resource:///actors/ContentSearchParent.jsm, line 664: TypeError: can't access property "addEventListener", browser is null
[task 2022-04-21T09:00:59.392Z] 09:00:59     INFO - Console message: [JavaScript Error: "TypeError: can't access property "addEventListener", browser is null" {file: "resource:///actors/ContentSearchParent.jsm" line: 664}]
[task 2022-04-21T09:00:59.392Z] 09:00:59     INFO - receiveMessage@resource:///actors/ContentSearchParent.jsm:664:5
[task 2022-04-21T09:00:59.392Z] 09:00:59     INFO - JSActor query*handleEvent@resource:///actors/ContentSearchChild.jsm:14:12
[task 2022-04-21T09:00:59.392Z] 09:00:59     INFO - _sendMsg@chrome://browser/content/contentSearchHandoffUI.js:143:18
[task 2022-04-21T09:00:59.392Z] 09:00:59     INFO - ContentSearchHandoffUIController@chrome://browser/content/contentSearchHandoffUI.js:13:8
[task 2022-04-21T09:00:59.393Z] 09:00:59     INFO - onInputMountHandoff@resource://activity-stream/data/content/activity-stream.bundle.js:14126:39
[task 2022-04-21T09:00:59.393Z] 09:00:59     INFO - Aj@resource://activity-stream/vendor/react-dom.js:161:87
<...>
[task 2022-04-21T09:00:59.396Z] 09:00:59     INFO - handleEvent@resource:///actors/BrowserTabChild.jsm:36:14
[task 2022-04-21T09:00:59.396Z] 09:00:59     INFO - 
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - Console message: [JavaScript Error: "TypeError: can't access property "addEventListener", browser is null" {file: "resource:///actors/ContentSearchParent.jsm" line: 664}]
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - receiveMessage@resource:///actors/ContentSearchParent.jsm:664:5
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - JSActor query*handleEvent@resource:///actors/ContentSearchChild.jsm:14:12
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - _sendMsg@chrome://browser/content/contentSearchHandoffUI.js:143:18
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - ContentSearchHandoffUIController@chrome://browser/content/contentSearchHandoffUI.js:14:8
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - onInputMountHandoff@resource://activity-stream/data/content/activity-stream.bundle.js:14126:39
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - Aj@resource://activity-stream/vendor/react-dom.js:161:87
[task 2022-04-21T09:00:59.400Z] 09:00:59     INFO - unstable_runWithPriority@resource://activity-stream/vendor/react.js:25:260
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Da@resource://activity-stream/vendor/react-dom.js:60:280
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - ab@resource://activity-stream/vendor/react-dom.js:154:192
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Te@resource://activity-stream/vendor/react-dom.js:146:357
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Pg/<@resource://activity-stream/vendor/react-dom.js:61:68
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - unstable_runWithPriority@resource://activity-stream/vendor/react.js:25:260
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Da@resource://activity-stream/vendor/react-dom.js:60:280
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Pg@resource://activity-stream/vendor/react-dom.js:61:16
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - ha@resource://activity-stream/vendor/react-dom.js:60:451
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - Qh@resource://activity-stream/vendor/react-dom.js:147:10
[task 2022-04-21T09:00:59.401Z] 09:00:59     INFO - notify@resource://activity-stream/vendor/react-redux.js:1:1399
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - notifyNestedSubs@resource://activity-stream/vendor/react-redux.js:1:1811
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - o@resource://activity-stream/vendor/react-redux.js:1:1234
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - dispatch@resource://activity-stream/vendor/redux.js:339:20
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - messageMiddleware/</<@resource://activity-stream/data/content/activity-stream.bundle.js:14592:9
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - rehydrationMiddleware/</<@resource://activity-stream/data/content/activity-stream.bundle.js:14624:14
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - queueEarlyMessageMiddleware/</<@resource://activity-stream/data/content/activity-stream.bundle.js:14663:11
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - initStore/<@resource://activity-stream/data/content/activity-stream.bundle.js:14690:15
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - callListeners@resource://gre/modules/remotepagemanager/MessagePort.jsm:36:17
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - handleMessage@resource://gre/modules/remotepagemanager/RemotePageManagerChild.jsm:78:19
[task 2022-04-21T09:00:59.402Z] 09:00:59     INFO - message@resource://gre/modules/remotepagemanager/MessagePort.jsm:203:10
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - MessageListener.receiveMessage*addMessageListeners@resource://gre/modules/remotepagemanager/MessagePort.jsm:91:25
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - MessagePort@resource://gre/modules/remotepagemanager/MessagePort.jsm:83:10
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - ChildMessagePort@resource://gre/modules/remotepagemanager/RemotePageManagerChild.jsm:19:5
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - initializeRPM@resource:///actors/BrowserTabChild.jsm:124:7
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - handleEvent@resource:///actors/BrowserTabChild.jsm:36:14
[task 2022-04-21T09:00:59.403Z] 09:00:59     INFO - 
[task 2022-04-21T09:00:59.404Z] 09:00:59     INFO - Leaving test bound test_onCreated_active
[task 2022-04-21T09:00:59.404Z] 09:00:59     INFO - GECKO(7510) | MEMORY STAT | vsize 11351MB | residentFast 480MB | heapAllocated 233MB
[task 2022-04-21T09:00:59.405Z] 09:00:59     INFO - TEST-OK | browser/components/extensions/test/browser/browser_ext_tabs_onCreated.js | took 393ms
[task 2022-04-21T09:00:59.405Z] 09:00:59     INFO - checking window state
[task 2022-04-21T09:00:59.406Z] 09:00:59     INFO - GECKO(7510) | Completed ShutdownLeaks collections in process 7510
[task 2022-04-21T09:00:59.406Z] 09:00:59     INFO - TEST-START | Shutdown
[task 2022-04-21T09:00:59.407Z] 09:00:59     INFO - Browser Chrome Test Summary
[task 2022-04-21T09:00:59.407Z] 09:00:59     INFO - Passed:  2
[task 2022-04-21T09:00:59.408Z] 09:00:59     INFO - Failed:  1
[task 2022-04-21T09:00:59.408Z] 09:00:59     INFO - Todo:    0
[task 2022-04-21T09:00:59.409Z] 09:00:59     INFO - Mode:    e10s
[task 2022-04-21T09:00:59.409Z] 09:00:59     INFO - *** End BrowserChrome Test Results ***

The Bugbug bot thinks this bug should belong to the 'Firefox::Tabbed Browser' component, and is moving the bug to that component. Please correct in case you think the bot is wrong.

Component: Untriaged → Tabbed Browser
Product: WebExtensions → Firefox

:Oriol, since you are the author of the regressor, bug 1764452, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(oriol-bugzilla)
Has Regression Range: --- → yes

It seems that tab creation may sometimes trigger 'status' updates.
It may depend on whether about:newtab is cached or has to be loaded.

Assignee: nobody → oriol-bugzilla
Status: NEW → ASSIGNED

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

status-firefox100: --- → unaffected
status-firefox101: --- → affected
status-firefox99: --- → unaffected
status-firefox-esr91: --- → unaffected
Flags: needinfo?(oriol-bugzilla)
Pushed by archaeopteryx@coole-files.de: https://hg.mozilla.org/integration/autoland/rev/9c1da7057767 Fix intermittent failure in browser_ext_tabs_onCreated.js. r=mixedpuppy

https://hg.mozilla.org/mozilla-central/rev/9c1da7057767

Status: ASSIGNED → RESOLVED
Closed: 4 years ago
status-firefox101: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 101 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: