1644649 - Intermittent devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Test timed out -

Status: RESOLVED FIXED

(DevTools :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-06-10T06:11:55.543Z] 06:11:55 INFO - TEST-START | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js
[task 2020-06-10T06:11:56.129Z] 06:11:56 INFO - GECKO(6936) | (+) target: http://test1.example.org/browser/devtools/server/tests/browser/doc_iframe.html -- 414
[task 2020-06-10T06:11:56.148Z] 06:11:56 INFO - GECKO(6936) | (+) target: http://example.com/browser/devtools/server/tests/browser/doc_iframe_content.html -- 178241142786
[task 2020-06-10T06:11:56.148Z] 06:11:56 INFO - GECKO(6936) | (+) target: about:newtab -- 21
[task 2020-06-10T06:11:56.210Z] 06:11:56 INFO - GECKO(6936) | (+) target: http://example.com/browser/devtools/server/tests/browser/doc_iframe_content.html -- 178241142786
[task 2020-06-10T06:11:56.474Z] 06:11:56 INFO - GECKO(6936) | (+) target: http://example.net/browser/devtools/server/tests/browser/doc_iframe2.html -- 178241142788
[task 2020-06-10T06:11:56.496Z] 06:11:56 INFO - GECKO(6936) | (+) target: http://example.com/browser/devtools/server/tests/browser/doc_iframe_content.html -- 178241142786
[task 2020-06-10T06:11:56.521Z] 06:11:56 INFO - GECKO(6936) | JavaScript error: resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.jsm, line 102: Error: Watcher Actor with ID 'server0.conn4.watcher4' can't be found.
[task 2020-06-10T06:12:40.592Z] 06:12:40 INFO - TEST-INFO | started process screenshot
[task 2020-06-10T06:12:40.654Z] 06:12:40 INFO - TEST-INFO | screenshot: exit 0
[task 2020-06-10T06:12:40.654Z] 06:12:40 INFO - Buffered messages logged at 06:11:55
[task 2020-06-10T06:12:40.655Z] 06:12:40 INFO - Entering test bound
[task 2020-06-10T06:12:40.656Z] 06:12:40 INFO - Adding a new tab with URL: http://test1.example.org/browser/devtools/server/tests/browser/doc_iframe.html
[task 2020-06-10T06:12:40.656Z] 06:12:40 INFO - Buffered messages logged at 06:11:56
[task 2020-06-10T06:12:40.657Z] 06:12:40 INFO - Tab added a URL http://test1.example.org/browser/devtools/server/tests/browser/doc_iframe.html loaded
[task 2020-06-10T06:12:40.657Z] 06:12:40 INFO - Attaching to the active tab.
[task 2020-06-10T06:12:40.658Z] 06:12:40 INFO - Assert watchTargets against the whole browser
[task 2020-06-10T06:12:40.658Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Got multiple frame targets -
[task 2020-06-10T06:12:40.659Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | We get the target for the tab document -
[task 2020-06-10T06:12:40.659Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The tab frame target BrowsingContextID is correct -
[task 2020-06-10T06:12:40.660Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Parent process target getWatcher returns the same watcher -
[task 2020-06-10T06:12:40.660Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Tab parent target is the main process target - [task 2020-06-10T06:12:40.660Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | getBrowsingContextTarget returns the same target -
[task 2020-06-10T06:12:40.661Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | And its remote child iframe -
[task 2020-06-10T06:12:40.661Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The iframe target BrowsingContextID is correct -
[task 2020-06-10T06:12:40.661Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Iframe target getWatcher returns the same watcher -
[task 2020-06-10T06:12:40.661Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | getBrowsingContextTarget returns the same target -
[task 2020-06-10T06:12:40.662Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | getParentBrowsingContextTarget returned the tab target -
[task 2020-06-10T06:12:40.662Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The iframe parent target is the tab target -
[task 2020-06-10T06:12:40.663Z] 06:12:40 INFO - Waiting for previous target destruction
[task 2020-06-10T06:12:40.663Z] 06:12:40 INFO - Waiting for new target creation
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | the destroyed target is the expected one, i.e. the previous one -
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | the created target is the expected one, i.e. a new one -
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The new iframe target BrowsingContextID is the same -
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The created iframe parent target is also the tab target -
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Got the additional frame -
[task 2020-06-10T06:12:40.664Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | We got the target for the new iframe -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The new iframe parent target is also the tab target -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | We got notified about the iframe destruction -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - Assert watchTargets against a given Tab
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Tab target getWatcher returns the same watcher -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | With fission, one additional target is reported -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | And its remote child iframe -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The iframe target BrowsingContextID is correct -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Iframe target getWatcher returns the same watcher -
[task 2020-06-10T06:12:40.665Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | getBrowsingContextTarget returns the same target -
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | getParentBrowsingContextTarget returned the tab target - [task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - TEST-PASS | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | The iframe parent target is the tab target -
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - Waiting for previous target destruction
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - Waiting for new target creation
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - Console message: [JavaScript Error: "Error: Watcher Actor with ID 'server0.conn4.watcher4' can't be found." {file: "resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.jsm" line: 102}]
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - connectFromContent@resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.jsm:102:13
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - receiveMessage@resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.jsm:209:21
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - JSActor query*_createTargetActor@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:216:10
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - instantiate@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:152:14
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO - handleEvent@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:445:12
[task 2020-06-10T06:12:40.666Z] 06:12:40 INFO -
[task 2020-06-10T06:12:40.667Z] 06:12:40 INFO - Buffered messages finished
[task 2020-06-10T06:12:40.667Z] 06:12:40 INFO - TEST-UNEXPECTED-FAIL | devtools/server/tests/browser/browser_watcher-watchTargets-frames.js | Test timed out -

Comment 1

[Intermittent Failures Robot]

13 failures in 5211 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 13

Platform breakdown:

• windows10-64: 6
• linux1804-64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 2

[Intermittent Failures Robot]

16 failures in 4035 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 1
• try: 10

Platform breakdown:

• windows10-64: 4
• linux1804-64: 8
• linux1804-64-shippable: 1
• macosx1014-64-shippable: 2
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-06-15&endday=2020-06-21&tree=all

Comment 3

[Intermittent Failures Robot]

9 failures in 5252 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 9

Platform breakdown:

• linux1804-64: 7
• windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-06-22&endday=2020-06-28&tree=all

Comment 4

[Intermittent Failures Robot]

10 failures in 4516 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 9
• try: 1

Platform breakdown:

• windows10-64: 7
• linux1804-64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-06-29&endday=2020-07-05&tree=all

Comment 5

[Alexandre Poirot [:ochameau] [PTO back August 21]]

Attachment: Bug 1644649 - Flush sharedData from WatcherRegistry in order to prevent races.

If we don't, content processes may have outdated data and create a target actor
for a watcher that no longer watch for targets.

Comment 6

[Chris Peterson [:cpeterson]]

Tracking dt-fission-m2-reserve bugs for Fission Beta milestone (M7).

Comment 7

[Intermittent Failures Robot]

37 failures in 5265 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

This is the #46 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:

• try: 23
• mozilla-central: 14

Platform breakdown:

• linux1804-64: 26
• macosx1014-64-shippable: 1
• windows10-64: 8
• macosx1014-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 8

[Intermittent Failures Robot]

10 failures in 4014 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 10

Platform breakdown:

• windows10-64: 8
• linux1804-64: 2

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

Comment 9

[Intermittent Failures Robot]

9 failures in 4310 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 9

Platform breakdown:

• windows10-64: 8
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-07-20&endday=2020-07-26&tree=all

Comment 10

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/e90d718bf339
Flush sharedData from WatcherRegistry in order to prevent races. r=jdescottes

Comment 11

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

https://hg.mozilla.org/mozilla-central/rev/e90d718bf339

Comment 12

[Intermittent Failures Robot]

1 failures in 4289 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1644649&startday=2020-07-27&endday=2020-08-02&tree=all