1698212 - Intermittent devtools/shared/resources/tests/browser_target_list_frames.js | The new target should be the newly loaded document - Got "about:blank", expected "https://example.org/document-builder.sjs?html=org"

Status: RESOLVED FIXED

(DevTools :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=333067203&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KSPDaT-fRma5lRmFmiVFPA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-03-12T22:47:37.019Z] 22:47:37     INFO - TEST-START | devtools/shared/resources/tests/browser_target_list_frames.js
[task 2021-03-12T22:47:37.732Z] 22:47:37     INFO - GECKO(10719) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 196: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-03-12T22:47:38.164Z] 22:47:38     INFO - GECKO(10719) | console.warn: "Listener for event 'frame' did not return a promise."
[task 2021-03-12T22:47:38.312Z] 22:47:38     INFO - GECKO(10719) | console.warn: "Listener for event 'frame' did not return a promise."
[task 2021-03-12T22:47:38.318Z] 22:47:38     INFO - TEST-INFO | started process screentopng
[task 2021-03-12T22:47:38.655Z] 22:47:38     INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-12T22:47:38.656Z] 22:47:38     INFO - Buffered messages logged at 22:47:37
[task 2021-03-12T22:47:38.656Z] 22:47:38     INFO - Entering test bound 
[task 2021-03-12T22:47:38.657Z] 22:47:38     INFO - Test TargetCommand against frames via the parent process target
[task 2021-03-12T22:47:38.658Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieve the target for the browser document - 
[task 2021-03-12T22:47:38.659Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieved the same number of frames - 
[task 2021-03-12T22:47:38.659Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame 0 targets are the same - 
[task 2021-03-12T22:47:38.659Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame 1 targets are the same - 
[task 2021-03-12T22:47:38.660Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.660Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | isTopLevel property is correct - 
[task 2021-03-12T22:47:38.661Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.662Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | isTopLevel property is correct - 
[task 2021-03-12T22:47:38.662Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieved the same number of frames via watchTargets - 
[task 2021-03-12T22:47:38.663Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame 0 targets are the same via watchTargets - 
[task 2021-03-12T22:47:38.663Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame 1 targets are the same via watchTargets - 
[task 2021-03-12T22:47:38.663Z] 22:47:38     INFO - Test TargetCommand against frames via a tab target
[task 2021-03-12T22:47:38.664Z] 22:47:38     INFO - Adding a new tab with URL: https://example.com/browser/devtools/shared/resources/tests/fission_document.html
[task 2021-03-12T22:47:38.665Z] 22:47:38     INFO - Console message: [JavaScript Warning: "debuggee 'resource://devtools/shared/base-loader.js:289' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 196}]
[task 2021-03-12T22:47:38.665Z] 22:47:38     INFO - Buffered messages logged at 22:47:38
[task 2021-03-12T22:47:38.666Z] 22:47:38     INFO - Tab added and finished loading
[task 2021-03-12T22:47:38.666Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieved only the top level document - 
[task 2021-03-12T22:47:38.666Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.666Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.667Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieved the same number of frames via watchTargets - 
[task 2021-03-12T22:47:38.668Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | Should be no destroyed target initialy - 
[task 2021-03-12T22:47:38.669Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame server0.conn11.child15/frameTarget1 target is the same via watchTargets - 
[task 2021-03-12T22:47:38.670Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | frame server0.conn11.windowGlobal96636764161/frameTarget1 target is the same via watchTargets - 
[task 2021-03-12T22:47:38.670Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | First target should be the top document one - 
[task 2021-03-12T22:47:38.671Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | First target is a top level one - 
[task 2021-03-12T22:47:38.674Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | First target is not considered as a target switching - 
[task 2021-03-12T22:47:38.675Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | Second target should be the iframe one - 
[task 2021-03-12T22:47:38.676Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | Iframe target isn't top level - 
[task 2021-03-12T22:47:38.676Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | Iframe target isn't a target swich - 
[task 2021-03-12T22:47:38.677Z] 22:47:38     INFO - Navigate to another domain and process (if fission is enabled)
[task 2021-03-12T22:47:38.677Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.678Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | isTopLevel property is correct - 
[task 2021-03-12T22:47:38.678Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.679Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | isTopLevel property is correct - 
[task 2021-03-12T22:47:38.680Z] 22:47:38     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.org/document-builder.sjs?html=org" line: 0}]
[task 2021-03-12T22:47:38.680Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | We are only notified about frame targets - 
[task 2021-03-12T22:47:38.681Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | retrieved all targets after navigation - 
[task 2021-03-12T22:47:38.681Z] 22:47:38     INFO - Buffered messages finished
[task 2021-03-12T22:47:38.685Z] 22:47:38     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/resources/tests/browser_target_list_frames.js | The new target should be the newly loaded document - Got "about:blank", expected "https://example.org/document-builder.sjs?html=org"
[task 2021-03-12T22:47:38.685Z] 22:47:38     INFO - Stack trace:
[task 2021-03-12T22:47:38.686Z] 22:47:38     INFO - chrome://mochikit/content/browser-test.js:test_is:1359
[task 2021-03-12T22:47:38.686Z] 22:47:38     INFO - chrome://mochitests/content/browser/devtools/shared/resources/tests/browser_target_list_frames.js:testTabFrames:233
[task 2021-03-12T22:47:38.687Z] 22:47:38     INFO - chrome://mochitests/content/browser/devtools/shared/resources/tests/browser_target_list_frames.js:null:28
[task 2021-03-12T22:47:38.687Z] 22:47:38     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1089
[task 2021-03-12T22:47:38.688Z] 22:47:38     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1129
[task 2021-03-12T22:47:38.688Z] 22:47:38     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:949
[task 2021-03-12T22:47:38.688Z] 22:47:38     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:1037
[task 2021-03-12T22:47:38.689Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | and should be flagged as a target switching - 
[task 2021-03-12T22:47:38.689Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | The two existing targets should be destroyed - 
[task 2021-03-12T22:47:38.690Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | The first destroyed should be the previous top level one - 
[task 2021-03-12T22:47:38.691Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | the target destruction is flagged as target switching - 
[task 2021-03-12T22:47:38.691Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | The second destroyed should be the iframe one - 
[task 2021-03-12T22:47:38.692Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | the target destruction is not flagged as target switching for iframes - 
[task 2021-03-12T22:47:38.692Z] 22:47:38     INFO - GECKO(10719) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-03-12T22:47:38.693Z] 22:47:38     INFO - Leaving test bound 
[task 2021-03-12T22:47:38.693Z] 22:47:38     INFO - TEST-PASS | devtools/shared/resources/tests/browser_target_list_frames.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-03-12T22:47:38.694Z] 22:47:38     INFO - GECKO(10719) | MEMORY STAT | vsize 2896MB | residentFast 367MB | heapAllocated 137MB
[task 2021-03-12T22:47:38.694Z] 22:47:38     INFO - TEST-OK | devtools/shared/resources/tests/browser_target_list_frames.js | took 1447ms```

Comment 1

[Intermittent Failures Robot]

12 failures in 4622 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 5

Platform and build breakdown:

• linux1804-64-shippable: 7
  • opt: 7
• linux1804-64: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1698212&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 2

[Alexandre Poirot [:ochameau]]

This intermittent seems to occur much more recently.

Comment 3

[Alexandre Poirot [:ochameau]]

Attachment: Bug 1698212 - [devtools] Assert new target via outerWindowID rather than url.

Because targetFront.url may not be properly updated, this isn't a reliable attribute
to assert that we got the expected target.
First, the url passed via Target's form may be about:blank,
then will-navigate and navigate events might be missed and url won't be correctly updated.

Comment 4

[Intermittent Failures Robot]

57 failures in 4257 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #13 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: 38
• mozilla-central: 16
• try: 3

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-64-qr: 2
  • opt: 1
  • debug: 1
• macosx1014-64-qr: 2
  • opt: 1
  • debug: 1
• linux1804-64-shippable: 27
  • opt: 27
• linux1804-64: 24
  • debug: 24

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1698212&startday=2021-03-15&endday=2021-03-21&tree=all

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/111917b65a09
[devtools] Assert new target via outerWindowID rather than url. r=jdescottes

Comment 6

[Bogdan Tara[:bogdan_tara | bogdant]]

https://hg.mozilla.org/mozilla-central/rev/111917b65a09

Comment 7

[Intermittent Failures Robot]

3 failures in 3967 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3

Platform and build breakdown:

• linux1804-64: 2
  • debug: 2
• linux1804-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1698212&startday=2021-03-22&endday=2021-03-28&tree=all