1811544 - Intermittent devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | single tracking bug

Status: RESOLVED FIXED

(DevTools :: about:debugging, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-01-20T17:18:20.379Z] 17:18:20     INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js
[task 2023-01-20T17:18:22.802Z] 17:18:22     INFO - GECKO(7272) | Controlled service worker registered
[task 2023-01-20T17:18:23.706Z] 17:18:23     INFO - TEST-INFO | started process screenshot
[task 2023-01-20T17:18:23.806Z] 17:18:23     INFO - TEST-INFO | screenshot: exit 0
[task 2023-01-20T17:18:23.811Z] 17:18:23     INFO - Buffered messages logged at 17:18:20
[task 2023-01-20T17:18:23.811Z] 17:18:23     INFO - Entering test bound 
[task 2023-01-20T17:18:23.812Z] 17:18:23     INFO - opening about:debugging
[task 2023-01-20T17:18:23.812Z] 17:18:23     INFO - Adding a new tab with URL: about:debugging
[task 2023-01-20T17:18:23.813Z] 17:18:23     INFO - Tab added and finished loading
[task 2023-01-20T17:18:23.813Z] 17:18:23     INFO - Wait until Connect page is displayed
[task 2023-01-20T17:18:23.814Z] 17:18:23     INFO - Buffered messages logged at 17:18:21
[task 2023-01-20T17:18:23.814Z] 17:18:23     INFO - Select This Firefox page
[task 2023-01-20T17:18:23.814Z] 17:18:23     INFO - Wait for requests to be complete
[task 2023-01-20T17:18:23.815Z] 17:18:23     INFO - Wait for runtime page to be rendered
[task 2023-01-20T17:18:23.816Z] 17:18:23     INFO - Buffered messages logged at 17:18:22
[task 2023-01-20T17:18:23.816Z] 17:18:23     INFO - Adding a new tab with URL: https://example.com/browser/devtools/client/aboutdebugging/test/browser/resources/service-workers/controlled-sw.html
[task 2023-01-20T17:18:23.817Z] 17:18:23     INFO - Console message: [JavaScript Warning: "Unknown category for SetEventRecordingEnabled: page_load"]
[task 2023-01-20T17:18:23.817Z] 17:18:23     INFO - Tab added and finished loading
[task 2023-01-20T17:18:23.818Z] 17:18:23     INFO - Wait until the registration appears on the window
[task 2023-01-20T17:18:23.818Z] 17:18:23     INFO - Open a toolbox to debug the worker
[task 2023-01-20T17:18:23.819Z] 17:18:23     INFO - Open about:devtools-toolbox page
[task 2023-01-20T17:18:23.819Z] 17:18:23     INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | https://example.com/browser/devtools/client/aboutdebugging/test/browser/resources/service-workers/controlled-sw.js tab target appeared - 
[task 2023-01-20T17:18:23.820Z] 17:18:23     INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | Inspect button for https://example.com/browser/devtools/client/aboutdebugging/test/browser/resources/service-workers/controlled-sw.js appeared - 
[task 2023-01-20T17:18:23.820Z] 17:18:23     INFO - Buffered messages logged at 17:18:23
[task 2023-01-20T17:18:23.821Z] 17:18:23     INFO - Wait for about:devtools-toolbox tab will be selected
[task 2023-01-20T17:18:23.822Z] 17:18:23     INFO - Assert the default tools displayed in worker toolboxes
[task 2023-01-20T17:18:23.822Z] 17:18:23     INFO - Buffered messages finished
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | Correct set of tools supported by worker - Got "", expected "webconsole,jsdebugger"
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - Stack trace:
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/browser-test.js:test_is:1484
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js:null:56
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/browser-test.js:handleTask:1037
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1109
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1251
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1026
[task 2023-01-20T17:18:23.824Z] 17:18:23     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1036
[task 2023-01-20T17:18:23.825Z] 17:18:23     INFO - Evaluate location in the console
[task 2023-01-20T17:18:23.825Z] 17:18:23     INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | Got the location logged in the console - 
[task 2023-01-20T17:18:23.825Z] 17:18:23     INFO - Evaluate Date and RegExp to ensure their formater also work from worker threads
[task 2023-01-20T17:18:23.826Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/customElements.js" line: 500 column: 24 source: "display: none !important"}]
[task 2023-01-20T17:18:23.827Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/customElements.js" line: 500}]
[task 2023-01-20T17:18:23.828Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2023-01-20T17:18:23.828Z] 17:18:23     INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | Date object has expected text content - 
[task 2023-01-20T17:18:23.829Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/customElements.js" line: 500 column: 24 source: "display: none !important"}]
[task 2023-01-20T17:18:23.829Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/customElements.js" line: 500}]
[task 2023-01-20T17:18:23.830Z] 17:18:23     INFO - Console message: [JavaScript Error: "Content Security Policy: The pages settings blocked the loading of a resource at inline (default-src)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2023-01-20T17:18:23.831Z] 17:18:23     INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | RegExp has expected text content - 

Comment 1

[Iulian Moraru]

First occurrence, so far: backfill range and retriggers.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • opt: 1
• linux1804-64-qr: 5
  • opt: 5
• linux1804-64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-01-16&endday=2023-01-22&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 23
• mozilla-central: 5

Platform and build breakdown:

• linux1804-64-shippable-qr: 10
  • opt: 10
• linux1804-64-qr: 18
  • opt: 18

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-01-23&endday=2023-01-29&tree=all

Comment 4

[Intermittent Failures Robot]

28 failures in 3606 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 24
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-shippable-qr: 9
  • opt: 9
• linux1804-64-qr: 17
  • opt: 15
  • debug: 2
• macosx1015-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-01-30&endday=2023-02-05&tree=all

Comment 5

[Intermittent Failures Robot]

22 failures in 3766 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 19
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-qr: 16
  • opt: 16
• linux1804-64-shippable-qr: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-02-06&endday=2023-02-12&tree=all

Comment 6

[Marian-Vasile Laza]

There have been 33 total failures in the last 7 days, recent failure log.
Affected platforms are:

• linux1804-64-qr
• linux1804-64-shippable-qr
• macosx1015-64-qr

[task 2023-02-18T06:24:01.020Z] 06:24:01     INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js
[task 2023-02-18T06:24:02.680Z] 06:24:02     INFO - GECKO(1572) | Controlled service worker registered
[task 2023-02-18T06:24:02.700Z] 06:24:02     INFO - TEST-INFO | started process screentopng
[task 2023-02-18T06:24:03.062Z] 06:24:03     INFO - TEST-INFO | screentopng: exit 0
[task 2023-02-18T06:24:03.063Z] 06:24:03     INFO - Buffered messages logged at 06:24:01
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - Entering test bound 
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - opening about:debugging
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - Adding a new tab with URL: about:debugging
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - Tab added and finished loading
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - Wait until Connect page is displayed
[task 2023-02-18T06:24:03.064Z] 06:24:03     INFO - Select This Firefox page
[task 2023-02-18T06:24:03.065Z] 06:24:03     INFO - Wait for requests to be complete
[task 2023-02-18T06:24:03.066Z] 06:24:03     INFO - Wait for runtime page to be rendered
[task 2023-02-18T06:24:03.067Z] 06:24:03     INFO - Buffered messages logged at 06:24:02
[task 2023-02-18T06:24:03.068Z] 06:24:03     INFO - Adding a new tab with URL: https://example.com/browser/devtools/client/aboutdebugging/test/browser/resources/service-workers/controlled-sw.html
[task 2023-02-18T06:24:03.070Z] 06:24:03     INFO - Tab added and finished loading
[task 2023-02-18T06:24:03.071Z] 06:24:03     INFO - Wait until the registration appears on the window
[task 2023-02-18T06:24:03.072Z] 06:24:03     INFO - Open a toolbox to debug the worker
[task 2023-02-18T06:24:03.073Z] 06:24:03     INFO - Open about:devtools-toolbox page
[task 2023-02-18T06:24:03.073Z] 06:24:03     INFO - Buffered messages finished
[task 2023-02-18T06:24:03.076Z] 06:24:03     INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js | https://example.com/browser/devtools/client/aboutdebugging/test/browser/resources/service-workers/controlled-sw.js tab target appeared - 
[task 2023-02-18T06:24:03.076Z] 06:24:03     INFO - Stack trace:
[task 2023-02-18T06:24:03.076Z] 06:24:03     INFO - chrome://mochikit/content/browser-test.js:test_ok:1465
[task 2023-02-18T06:24:03.076Z] 06:24:03     INFO - chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/head.js:openAboutDevtoolsToolbox:85
[task 2023-02-18T06:24:03.077Z] 06:24:03     INFO - chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_console.js:null:40
[task 2023-02-18T06:24:03.077Z] 06:24:03     INFO - chrome://mochikit/content/browser-test.js:handleTask:1037
[task 2023-02-18T06:24:03.077Z] 06:24:03     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1109
[task 2023-02-18T06:24:03.077Z] 06:24:03     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1251
[task 2023-02-18T06:24:03.078Z] 06:24:03     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1026
[task 2023-02-18T06:24:03.078Z] 06:24:03     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1036
[task 2023-02-18T06:24:03.079Z] 06:24:03     INFO - Not taking screenshot here: see the one that was previously logged

Comment 7

[Intermittent Failures Robot]

34 failures in 3856 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

** 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-central: 7

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1
• linux1804-64-shippable-qr: 18
  • opt: 18
• linux1804-64-qr: 15
  • opt: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-02-13&endday=2023-02-19&tree=all

Comment 8

[Intermittent Failures Robot]

29 failures in 3643 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• autoland: 25
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-qr: 21
  • opt: 21
• linux1804-64-shippable-qr: 8
  • opt: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-02-20&endday=2023-02-26&tree=all

Comment 9

[Julian Descottes [:jdescottes]]

Attachment: Bug 1811544 - [devtools] Always wait for target in aboutdebugging tests

Comment 10

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ccf1d34a110e
[devtools] Always wait for target in aboutdebugging tests r=nchevobbe

Comment 11

[Marian-Vasile Laza]

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7

Platform and build breakdown:

• linux1804-64-qr: 4
  • opt: 4
• linux1804-64-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1811544&startday=2023-02-27&endday=2023-03-05&tree=all