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)
Tracking
()
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.
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.
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.
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.
Description
•