1894693 - Intermittent devtools/client/styleeditor/test/browser_styleeditor_sourcemap_large.js | single tracking bug

Status: RESOLVED INCOMPLETE

(DevTools :: Style Editor, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2024-05-02T13:32:38.431Z] 13:32:38     INFO - TEST-START | devtools/client/styleeditor/test/browser_styleeditor_sourcemap_large.js
[task 2024-05-02T13:32:38.454Z] 13:32:38     INFO - GECKO(3236) | [Child 7125: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f48bd0dd200) [pid = 7125] [serial = 1] [outer = 0] [url = https://example.com/browser/devtools/client/styleeditor/test/doc_sourcemap_chrome.html]
[task 2024-05-02T13:32:38.457Z] 13:32:38     INFO - GECKO(3236) | [Child 7125: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f48bcb73400) [pid = 7125] [serial = 3] [outer = 0] [url = https://example.com/browser/devtools/client/styleeditor/test/doc_sourcemap_chrome.html]
[task 2024-05-02T13:32:38.873Z] 13:32:38     INFO - GECKO(3236) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp8ifi0cw1.mozrunner/runtests_leaks_tab_pid7224.log
[task 2024-05-02T13:32:38.925Z] 13:32:38     INFO - GECKO(3236) | [Child 7224, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:720
[task 2024-05-02T13:32:39.066Z] 13:32:39     INFO - GECKO(3236) | [Parent 3236, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port '1F5262C7BD281E93.8CF2E88018DFA99F' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:425
[task 2024-05-02T13:32:39.176Z] 13:32:39     INFO - GECKO(3236) | [Child 3410: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fe26ce83800) [pid = 3410] [serial = 46] [outer = 0] [url = about:blank]
[task 2024-05-02T13:32:39.215Z] 13:32:39     INFO - GECKO(3236) | [Parent 3236, Main Thread] WARNING: NS_ENSURE_TRUE(mBounceTrackingRecord) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:585
[task 2024-05-02T13:32:39.218Z] 13:32:39     INFO - GECKO(3236) | [Parent 3236, Main Thread] WARNING: BounceTrackingState::OnDocumentStartRequest failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/netwerk/ipc/DocumentLoadListener.cpp:2581
[task 2024-05-02T13:32:39.397Z] 13:32:39     INFO - GECKO(3236) | [Child 7224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f799b16fc00 == 1 [pid = 7224] [id = 0]
[task 2024-05-02T13:32:39.399Z] 13:32:39     INFO - GECKO(3236) | [Child 7224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f799b7de200) [pid = 7224] [serial = 1] [outer = 0]
[task 2024-05-02T13:32:39.402Z] 13:32:39     INFO - GECKO(3236) | [Child 7224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f799b170400) [pid = 7224] [serial = 2] [outer = 7f799b7de200]
[task 2024-05-02T13:32:39.511Z] 13:32:39     INFO - GECKO(3236) | [Child 7224: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f799b173400) [pid = 7224] [serial = 3] [outer = 7f799b7de200]
[task 2024-05-02T13:32:40.247Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236, Main Thread] WARNING: NS_ENSURE_TRUE(mBounceTrackingRecord) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:657
[task 2024-05-02T13:32:40.308Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f5689630000 == 7 [pid = 3236] [id = 165]
[task 2024-05-02T13:32:40.310Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 23 (7f56898af6a0) [pid = 3236] [serial = 380] [outer = 0]
[task 2024-05-02T13:32:40.312Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 24 (7f5667879800) [pid = 3236] [serial = 381] [outer = 7f56898af6a0]
[task 2024-05-02T13:32:40.446Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 25 (7f5667879000) [pid = 3236] [serial = 382] [outer = 7f56898af6a0]
[task 2024-05-02T13:32:40.939Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f5667880c00 == 8 [pid = 3236] [id = 166]
[task 2024-05-02T13:32:40.941Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 26 (7f56898af100) [pid = 3236] [serial = 383] [outer = 0]
[task 2024-05-02T13:32:40.943Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 27 (7f568962c800) [pid = 3236] [serial = 384] [outer = 7f56898af100]
[task 2024-05-02T13:32:40.955Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-05-02T13:32:40.957Z] 13:32:40     INFO - GECKO(3236) | [Parent 3236, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-05-02T13:38:51.159Z] 13:38:51     INFO - Buffered messages logged at 13:32:38
[task 2024-05-02T13:38:51.161Z] 13:38:51     INFO - Entering test bound 
[task 2024-05-02T13:38:51.166Z] 13:38:51     INFO - Adding a new tab with URL: 'https://example.com/browser/devtools/client/styleeditor/test/sourcemaps-large.html'
[task 2024-05-02T13:38:51.167Z] 13:38:51     INFO - Buffered messages logged at 13:32:40
[task 2024-05-02T13:38:51.167Z] 13:38:51     INFO - URL 'https://example.com/browser/devtools/client/styleeditor/test/sourcemaps-large.html' loading complete
[task 2024-05-02T13:38:51.168Z] 13:38:51     INFO - Console message: [JavaScript Warning: "This page uses the non standard property “zoom”. Consider using calc() in the relevant property values, or using “transform” along with “transform-origin: 0 0”." {file: "https://example.com/browser/devtools/client/styleeditor/test/sourcemaps-large.html" line: 0}]
[task 2024-05-02T13:38:51.168Z] 13:38:51     INFO - Buffered messages logged at 13:32:41
[task 2024-05-02T13:38:51.168Z] 13:38:51     INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2024-05-02T13:38:51.169Z] 13:38:51     INFO - Console message: [JavaScript Error: "Content-Security-Policy: The page’s settings blocked an inline style (style-src-attr) from being applied because it violates the following directive: “default-src chrome: resource:”" {file: "chrome://global/content/customElements.js" line: 499 column: 25 source: "display: none !important"}]
[task 2024-05-02T13:38:51.169Z] 13:38:51     INFO - Console message: [JavaScript Error: "Content-Security-Policy: The page’s settings blocked an inline style (style-src-attr) from being applied because it violates the following directive: “default-src chrome: resource:”" {file: "chrome://global/content/customElements.js" line: 499}]
[task 2024-05-02T13:38:51.170Z] 13:38:51     INFO - Console message: [JavaScript Error: "Content-Security-Policy: The page’s settings blocked an inline style (style-src-attr) from being applied because it violates the following directive: “default-src chrome: resource:”" {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2024-05-02T13:38:51.170Z] 13:38:51     INFO - Buffered messages finished
[task 2024-05-02T13:38:51.170Z] 13:38:51     INFO - TEST-UNEXPECTED-TIMEOUT | devtools/client/styleeditor/test/browser_styleeditor_sourcemap_large.js | application timed out after 370 seconds with no output
[task 2024-05-02T13:38:51.171Z] 13:38:51     INFO - TEST-INFO took 372571ms
[task 2024-05-02T13:38:51.171Z] 13:38:51     INFO - Buffered messages finished
[task 2024-05-02T13:38:51.171Z] 13:38:51  WARNING - Force-terminating active process(es).
[task 2024-05-02T13:38:51.171Z] 13:38:51     INFO - Determining child pids from psutil...
[task 2024-05-02T13:38:51.175Z] 13:38:51     INFO - [3306, 3319, 3350, 3401, 3410, 3471, 3937, 7224]
[task 2024-05-02T13:38:51.176Z] 13:38:51     INFO - ==> process 3236 launched child process 3306
<...>
[task 2024-05-02T13:38:51.667Z] 13:38:51     INFO - Killing process: 3236
[task 2024-05-02T13:38:51.667Z] 13:38:51     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-02T13:38:52.031Z] 13:38:52     INFO - psutil found pid 3236 dead
[task 2024-05-02T13:55:32.031Z] 13:55:32     INFO - Automation Error: mozharness timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/mochitest/runtests.py', 'devtools/client/framework/test/browser-telemetry-startup.toml', 'devtools/client/inspector/shared/test/browser.toml', 'devtools/client/styleeditor/test/browser.toml', 'devtools/shared/heapsnapshot/tests/browser/browser.toml', '--setpref=layout.css.stylo-threads=4', '--setpref=network.process.enabled=true', '--setpref=network.http.network_access_on_socket_process.enabled=true', '--setpref=layers.d3d11.enable-blacklist=false', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=/builds/worker/workspace/build/symbols', '--certificate-path=tests/certs', '--setpref=webgl.force-enabled=true', '--quiet', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/mochitest-devtools-chrome_errorsummary.log', '--use-test-media-devices', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--flavor=browser', '--subsuite=devtools', '--chunk-by-runtime']
[task 2024-05-02T13:55:32.034Z] 13:55:32     INFO - Return code: -9
[task 2024-05-02T13:55:32.035Z] 13:55:32  WARNING - Got 1 unexpected statuses
[task 2024-05-02T13:55:32.035Z] 13:55:32    ERROR - No suite end message was emitted by this harness.
[task 2024-05-02T13:55:32.035Z] 13:55:32     INFO - TinderboxPrint: mochitest-mochitest-devtools-chrome<br/>56/<em class="testfail">1</em>/0
[task 2024-05-02T13:55:32.036Z] 13:55:32  WARNING - setting return code to 2
[task 2024-05-02T13:55:32.036Z] 13:55:32     INFO - The mochitest suite: mochitest-devtools-chrome ran with return status: FAILURE
[task 2024-05-02T13:55:32.037Z] 13:55:32     INFO - Running post-action listener: _package_coverage_data
[task 2024-05-02T13:55:32.037Z] 13:55:32     INFO - Running post-action listener: _resource_record_post_action
[task 2024-05-02T13:55:32.037Z] 13:55:32     INFO - Running post-action listener: process_java_coverage_data
[task 2024-05-02T13:55:32.038Z] 13:55:32     INFO - [mozharness: 2024-05-02 13:55:32.037932Z] Finished run-tests step (success)
[task 2024-05-02T13:55:32.038Z] 13:55:32     INFO - [mozharness: 2024-05-02 13:55:32.038037Z] Running uninstall step.
[task 2024-05-02T13:55:32.038Z] 13:55:32     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-05-02T13:55:32.039Z] 13:55:32     INFO - Running main action method: uninstall
[task 2024-05-02T13:55:32.039Z] 13:55:32     INFO - Skipping uninstall for non-MSIX test
[task 2024-05-02T13:55:32.039Z] 13:55:32     INFO - Running post-action listener: _resource_record_post_action
[task 2024-05-02T13:55:32.039Z] 13:55:32     INFO - [mozharness: 2024-05-02 13:55:32.039077Z] Finished uninstall step (success)
[task 2024-05-02T13:55:32.039Z] 13:55:32     INFO - Running post-run listener: _resource_record_post_run
[task 2024-05-02T13:55:32.761Z] 13:55:32     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-05-02T13:55:32.765Z] 13:55:32     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-devtools-chrome.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 24.871691614439452}, {"name": "io_write_bytes", "value": 618831872}, {"name": "io.read_bytes", "value": 10162176}, {"name": "io_write_time", "value": 433328}, {"name": "io_read_time", "value": 12176}]}, {"name": "mochitest.mochitest-devtools-chrome.start-pulseaudio", "subtests": [{"name": "time", "value": 0.027587356000026375}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-devtools-chrome.install", "subtests": [{"name": "time", "value": 15.84005480999997}, {"name": "cpu_percent", "value": 50.43184713375797}]}, {"name": "mochitest.mochitest-devtools-chrome.stage-files", "subtests": [{"name": "time", "value": 0.0007489980000059404}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-devtools-chrome.run-tests", "subtests": [{"name": "time", "value": 1914.5781857879997}, {"name": "cpu_percent", "value": 24.658233235846176}]}, {"name": "mochitest.mochitest-devtools-chrome.uninstall", "subtests": [{"name": "time", "value": 0.0003859770004055463}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-05-02T13:55:32.766Z] 13:55:32     INFO - Total resource usage - Wall time: 1931s; CPU: Can't collect data; Read bytes: 10162176; Write bytes: 618831872; Read time: 12176; Write time: 433328
[task 2024-05-02T13:55:32.767Z] 13:55:32     INFO - TinderboxPrint: I/O read bytes / time<br/>10,162,176 / 12,176
[task 2024-05-02T13:55:32.767Z] 13:55:32     INFO - TinderboxPrint: I/O write bytes / time<br/>618,831,872 / 433,328
[task 2024-05-02T13:55:32.767Z] 13:55:32     INFO - TinderboxPrint: CPU idle<br/>2,881.0 (74.8%)
[task 2024-05-02T13:55:32.768Z] 13:55:32     INFO - TinderboxPrint: CPU system<br/>79.9 (2.1%)
[task 2024-05-02T13:55:32.769Z] 13:55:32     INFO - TinderboxPrint: CPU user<br/>877.6 (22.8%)
[task 2024-05-02T13:55:32.769Z] 13:55:32     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-05-02T13:55:32.771Z] 13:55:32     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-02T13:55:32.775Z] 13:55:32     INFO - install - Wall time: 16s; CPU: 50%; Read bytes: 16384; Write bytes: 13320192; Read time: 56; Write time: 1020
[task 2024-05-02T13:55:32.777Z] 13:55:32     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-02T13:55:32.860Z] 13:55:32     INFO - run-tests - Wall time: 1915s; CPU: 25%; Read bytes: 10145792; Write bytes: 605511680; Read time: 12120; Write time: 432308
[task 2024-05-02T13:55:32.871Z] 13:55:32     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-02T13:55:35.986Z] 13:55:35  WARNING - returning nonzero exit status 2
[task 2024-05-02T13:55:36.129Z] cleanup
[task 2024-05-02T13:55:36.130Z] + cleanup
[task 2024-05-02T13:55:36.130Z] + local rv=2
[task 2024-05-02T13:55:36.130Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-05-02T13:55:36.130Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-05-02T13:55:36.265Z] + '[' ']'
[task 2024-05-02T13:55:36.265Z] + true
[task 2024-05-02T13:55:36.266Z] + cleanup_xvfb
[task 2024-05-02T13:55:36.267Z] ++ pidof Xvfb
[task 2024-05-02T13:55:36.391Z] + local xvfb_pid=49
[task 2024-05-02T13:55:36.391Z] + local vnc=false
[task 2024-05-02T13:55:36.391Z] + local interactive=false
[task 2024-05-02T13:55:36.391Z] + '[' -n 49 ']'
[task 2024-05-02T13:55:36.391Z] + [[ false == false ]]
[task 2024-05-02T13:55:36.392Z] + [[ false == false ]]
[task 2024-05-02T13:55:36.392Z] + kill 49
[task 2024-05-02T13:55:36.393Z] + screen -XS xvfb quit
[task 2024-05-02T13:55:36.609Z] No screen session found.
[task 2024-05-02T13:55:36.610Z] + true
[task 2024-05-02T13:55:36.610Z] + exit 2
[taskcluster 2024-05-02 13:55:38.680Z] === Task Finished ===
[taskcluster 2024-05-02 13:55:41.028Z] Unsuccessful task run with exit code: 2 completed in 2385.455 seconds

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1894693&startday=2024-04-29&endday=2024-05-05&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

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

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.