Closed
Bug 1776793
Opened 2 years ago
Closed 2 years ago
Intermittent TVw ::: Test verification FAIL | TinderboxPrint: Per-test run of .../structured-cloning-error-stack-optional.sub.window.js<br/>: FAILURE
Categories
(Core :: DOM: Core & HTML, defect, P5)
Core
DOM: Core & HTML
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Regression)
Details
(Keywords: intermittent-failure, regression)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=382680831&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NhzWOlOARi2ED4HXZADxhA/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/NhzWOlOARi2ED4HXZADxhA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2022-06-27T21:23:30.709Z] 21:23:30 INFO - ::: Ran 5 of expected 5 iterations.
[task 2022-06-27T21:23:30.709Z] 21:23:30 INFO - ## All results ##
[task 2022-06-27T21:23:30.709Z] 21:23:30 INFO -
[task 2022-06-27T21:23:30.710Z] 21:23:30 INFO - ### /html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.html ###
[task 2022-06-27T21:23:30.711Z] 21:23:30 INFO - | Subtest | Results | Messages |
[task 2022-06-27T21:23:30.712Z] 21:23:30 INFO - |----------------------------------------------------|---------|-----------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------|
[task 2022-06-27T21:23:30.713Z] 21:23:30 INFO - | | OK | |
[task 2022-06-27T21:23:30.714Z] 21:23:30 INFO - | `page-created Error (structuredClone())` | PASS | |
[task 2022-06-27T21:23:30.715Z] 21:23:30 INFO - | `page-created Error (worker)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:8:10\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:53:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:52:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:7:11\n" but got ""` |
[task 2022-06-27T21:23:30.716Z] 21:23:30 INFO - | `page-created Error (cross-site iframe)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:8:10\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:72:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:69:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:7:11\n" but got ""` |
[task 2022-06-27T21:23:30.717Z] 21:23:30 INFO - | `page-created DOMException (structuredClone())` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:12:10\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:41:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\ntest@http://web-platform.test:8000/resources/testharness.js:628:30\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:40:7\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:11:11\n" but got ""` |
[task 2022-06-27T21:23:30.718Z] 21:23:30 INFO - | `page-created DOMException (worker)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:12:10\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:53:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:52:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:11:11\n" but got ""` |
[task 2022-06-27T21:23:30.719Z] 21:23:30 INFO - | `page-created DOMException (cross-site iframe)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:12:10\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:72:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:69:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:11:11\n" but got ""` |
[task 2022-06-27T21:23:30.720Z] 21:23:30 INFO - | `JS-engine-created TypeError (structuredClone())` | PASS | |
[task 2022-06-27T21:23:30.722Z] 21:23:30 INFO - | `JS-engine-created TypeError (worker)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:17:12\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:53:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:52:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:15:11\n" but got ""` |
[task 2022-06-27T21:23:30.723Z] 21:23:30 INFO - | `JS-engine-created TypeError (cross-site iframe)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:17:12\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:72:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:69:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:15:11\n" but got ""` |
[task 2022-06-27T21:23:30.724Z] 21:23:30 INFO - | `web API-created TypeError (structuredClone())` | PASS | |
[task 2022-06-27T21:23:30.725Z] 21:23:30 INFO - | `web API-created TypeError (worker)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:25:5\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:53:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:52:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:23:11\n" but got ""` |
[task 2022-06-27T21:23:30.726Z] 21:23:30 INFO - | `web API-created TypeError (cross-site iframe)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:25:5\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:72:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:69:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:23:11\n" but got ""` |
[task 2022-06-27T21:23:30.727Z] 21:23:30 INFO - | `web API-created DOMException (structuredClone())` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:33:14\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:41:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\ntest@http://web-platform.test:8000/resources/testharness.js:628:30\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:40:7\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:31:11\n" but got ""` |
[task 2022-06-27T21:23:30.728Z] 21:23:30 INFO - | `web API-created DOMException (worker)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:33:14\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:53:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:52:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:31:11\n" but got ""` |
[task 2022-06-27T21:23:30.729Z] 21:23:30 INFO - | `web API-created DOMException (cross-site iframe)` | FAIL | `assert_equals: expected "@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:33:14\nstackTests/<@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:72:19\nTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25\nasync_test@http://web-platform.test:8000/resources/testharness.js:676:34\nstackTests@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:69:13\n@http://web-platform.test:8000/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.js:31:11\n" but got ""` |
[task 2022-06-27T21:23:30.729Z] 21:23:30 INFO -
[task 2022-06-27T21:23:30.730Z] 21:23:30 INFO - ## Slow tests ##
[task 2022-06-27T21:23:30.730Z] 21:23:30 INFO -
[task 2022-06-27T21:23:30.730Z] 21:23:30 INFO - | Test | Result | Longest duration (ms) | Timeout (ms) |
[task 2022-06-27T21:23:30.731Z] 21:23:30 INFO - |----------------------------------------------------------------------------------------------------------------|--------|-----------------------|--------------|
[task 2022-06-27T21:23:30.731Z] 21:23:30 INFO - | `/html/infrastructure/safe-passing-of-structured-data/structured-cloning-error-stack-optional.sub.window.html` | `OK` | `8950` | `10000` |
[task 2022-06-27T21:23:30.731Z] 21:23:30 INFO -
[task 2022-06-27T21:23:30.732Z] 21:23:30 INFO - ::: Running tests in a loop 10 times : PASS
[task 2022-06-27T21:23:30.732Z] 21:23:30 INFO - ::: Running tests in a loop with restarts 5 times : PASS
[task 2022-06-27T21:23:30.732Z] 21:23:30 INFO - ::: Running tests in a loop 10 times with flags chaos_mode_flags=0xfb : PASS
[task 2022-06-27T21:23:30.733Z] 21:23:30 INFO - ::: Running tests in a loop with restarts 5 times with flags chaos_mode_flags=0xfb : FAIL
[task 2022-06-27T21:23:30.733Z] 21:23:30 INFO - :::
[task 2022-06-27T21:23:30.733Z] 21:23:30 ERROR - ::: Test verification FAIL
[task 2022-06-27T21:23:30.734Z] 21:23:30 INFO - :::
[task 2022-06-27T21:23:31.034Z] 21:23:31 ERROR - Return code: 1
[task 2022-06-27T21:23:31.035Z] 21:23:31 ERROR - # TBPL FAILURE #
[task 2022-06-27T21:23:31.035Z] 21:23:31 WARNING - setting return code to 2
[task 2022-06-27T21:23:31.035Z] 21:23:31 ERROR - TinderboxPrint: Per-test run of .../structured-cloning-error-stack-optional.sub.window.js<br/>: FAILURE
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - Running post-action listener: _package_coverage_data
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - Running post-action listener: _resource_record_post_action
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - Running post-action listener: process_java_coverage_data
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - Running post-action listener: stop_device
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - [mozharness: 2022-06-27 21:23:31.035651Z] Finished run-tests step (success)
[task 2022-06-27T21:23:31.035Z] 21:23:31 INFO - Running post-run listener: _resource_record_post_run
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - Total resource usage - Wall time: 389s; CPU: 7%; Read bytes: 206786560; Write bytes: 869380096; Read time: 3889; Write time: 2759
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: CPU usage<br/>6.9%
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: I/O read bytes / time<br/>206,786,560 / 3,889
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: I/O write bytes / time<br/>869,380,096 / 2,759
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: CPU idle<br/>4,312.3 (92.4%)
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: CPU system<br/>98.1 (2.1%)
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: CPU user<br/>255.0 (5.5%)
[task 2022-06-27T21:23:31.128Z] 21:23:31 INFO - TinderboxPrint: Swap in / out<br/>550,703,104 / 0
[task 2022-06-27T21:23:31.129Z] 21:23:31 INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-06-27T21:23:31.129Z] 21:23:31 INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-06-27T21:23:31.129Z] 21:23:31 INFO - verify-device - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-06-27T21:23:31.130Z] 21:23:31 INFO - install - Wall time: 19s; CPU: 14%; Read bytes: 237196800; Write bytes: 225370112; Read time: 15821; Write time: 286
[task 2022-06-27T21:23:31.134Z] 21:23:31 INFO - run-tests - Wall time: 371s; CPU: 7%; Read bytes: 193720320; Write bytes: 613838848; Read time: 3161; Write time: 2449
[task 2022-06-27T21:23:31.222Z] 21:23:31 WARNING - returning nonzero exit status 2
[taskcluster 2022-06-27T21:23:31.499Z] Exit Code: 2
[taskcluster 2022-06-27T21:23:31.499Z] User Time: 4m7.240479s
[taskcluster 2022-06-27T21:23:31.499Z] Kernel Time: 1m0.819108s
[taskcluster 2022-06-27T21:23:31.499Z] Wall Time: 7m49.043606s
[taskcluster 2022-06-27T21:23:31.499Z] Result: FAILED
[taskcluster 2022-06-27T21:23:31.499Z] === Task Finished ===
|
||
Comment 1•2 years ago
|
||
:evilpie, since you are the author of the regressor, bug 1774866, could you take a look?
For more information, please visit auto_nag documentation.
Flags: needinfo?(evilpies)
|
||
Comment 2•2 years ago
|
||
Olli, do you know what these failures mean? I tried to see what test actually failed, but the All results table just seems to show the expected results. DOMException, worker and postMessage are supposed to fail.
Flags: needinfo?(evilpies) → needinfo?(bugs)
|
||
Comment 3•2 years ago
|
||
I don't understand the log.
It has Repetition 1 / 5 ... Repetition 5 / 5 for chaos mode all saying TEST-OK
but then it says something failed.
Worth to ask #sheriffs, someone there might be able to interpret the log.
Flags: needinfo?(bugs)
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•