Closed Bug 1790178 Opened 3 years ago Closed 3 years ago

Intermittent TVw ::: Test verification FAIL | TinderboxPrint: Per-test run of .../fs/opaque-origin.https.window.js<br/>: FAILURE

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1734020

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=390080936&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FjbP55IkTHaYqDtM6YMsLg/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/FjbP55IkTHaYqDtM6YMsLg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-09-10T05:07:10.141Z] 05:07:10     INFO - ::: Running tests in a loop 10 times : PASS
[task 2022-09-10T05:07:10.141Z] 05:07:10     INFO - ::: Running tests in a loop with restarts 5 times : PASS
[task 2022-09-10T05:07:10.141Z] 05:07:10     INFO - ::: Running tests in a loop 10 times with flags chaos_mode_flags=0xfb : FAIL
[task 2022-09-10T05:07:10.141Z] 05:07:10     INFO - :::
[task 2022-09-10T05:07:10.142Z] 05:07:10    ERROR - ::: Test verification FAIL
[task 2022-09-10T05:07:10.142Z] 05:07:10     INFO - :::
[task 2022-09-10T05:07:10.486Z] 05:07:10    ERROR - Return code: 1
[task 2022-09-10T05:07:10.486Z] 05:07:10    ERROR - # TBPL FAILURE #
[task 2022-09-10T05:07:10.486Z] 05:07:10  WARNING - setting return code to 2
[task 2022-09-10T05:07:10.487Z] 05:07:10    ERROR - TinderboxPrint: Per-test run of .../fs/opaque-origin.https.window.js<br/>: FAILURE
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - Running post-action listener: _package_coverage_data
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - Running post-action listener: _resource_record_post_action
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - Running post-action listener: process_java_coverage_data
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - Running post-action listener: stop_device
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - [mozharness: 2022-09-10 05:07:10.487500Z] Finished run-tests step (success)
[task 2022-09-10T05:07:10.487Z] 05:07:10     INFO - Running post-run listener: _resource_record_post_run
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - Total resource usage - Wall time: 209s; CPU: 7%; Read bytes: 131407872; Write bytes: 688361472; Read time: 1757; Write time: 2210
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: CPU usage<br/>7.3%
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: I/O read bytes / time<br/>131,407,872 / 1,757
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: I/O write bytes / time<br/>688,361,472 / 2,210
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: CPU idle<br/>2,322.1 (92.7%)
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: CPU system<br/>57.0 (2.3%)
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: CPU user<br/>126.0 (5.0%)
[task 2022-09-10T05:07:10.567Z] 05:07:10     INFO - TinderboxPrint: Swap in / out<br/>401,125,376 / 0
[task 2022-09-10T05:07:10.568Z] 05:07:10     INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-09-10T05:07:10.568Z] 05:07:10     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-09-10T05:07:10.568Z] 05:07:10     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-09-10T05:07:10.568Z] 05:07:10     INFO - install - Wall time: 21s; CPU: 14%; Read bytes: 249677312; Write bytes: 240230400; Read time: 16456; Write time: 448
[task 2022-09-10T05:07:10.571Z] 05:07:10     INFO - run-tests - Wall time: 189s; CPU: 7%; Read bytes: 121499648; Write bytes: 419155968; Read time: 1396; Write time: 1670
[task 2022-09-10T05:07:10.618Z] 05:07:10  WARNING - returning nonzero exit status 2
[taskcluster 2022-09-10T05:07:10.922Z]    Exit Code: 2
[taskcluster 2022-09-10T05:07:10.922Z]    User Time: 2m21.986922s
[taskcluster 2022-09-10T05:07:10.922Z]  Kernel Time: 42.124326s
[taskcluster 2022-09-10T05:07:10.922Z]    Wall Time: 4m44.275497s
[taskcluster 2022-09-10T05:07:10.922Z]       Result: FAILED
[taskcluster 2022-09-10T05:07:10.922Z] === Task Finished ===
[taskcluster 2022-09-10T05:07:10.922Z] Task Duration: 4m44.279219s
[taskcluster 2022-09-10T05:07:11.031Z] Uploading artifact public/logs/localconfig.json from file logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.249Z] Uploading artifact public/test_info/resource-usage.json from file build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.444Z] Uploading artifact public/test_info/wpt-test1_errorsummary.log from file build/blobber_upload_dir/wpt-test1_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.536Z] Uploading artifact public/test_info/wpt-test1_raw.log from file build/blobber_upload_dir/wpt-test1_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.692Z] Uploading artifact public/test_info/wpt_instruments.txt from file build/blobber_upload_dir/wpt_instruments.txt with content encoding "gzip", mime type "text/plain; charset=utf-8" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.796Z] Uploading artifact public/test_info/wptreport.json from file build/blobber_upload_dir/wptreport.json with content encoding "gzip", mime type "application/json" and expiry 2023-09-10T04:42:09.826Z
[taskcluster 2022-09-10T05:07:11.914Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FjbP55IkTHaYqDtM6YMsLg/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2023-09-10T04:42:09.826Z
[taskcluster:error] exit status 2

Part of the failure here is that the test is classified as "slow", only under chaos mode. This is bug 1734020.

However, there are other failures here as well. Here's the summary of the failures from the log:

INFO - ### /fs/opaque-origin.https.window.html ###
INFO - |                                   Subtest                                    | Results |                                                                        Messages                                                                        |
INFO - |------------------------------------------------------------------------------|---------|--------------------------------------------------------------------------------------------------------------------------------------------------------|
INFO - |                                                                              | OK      |                                                                                                                                                        |
INFO - | `FileSystemDirectoryHandle must be defined for data URI iframes.`            | FAIL    | `assert_true: Data URI iframes must define 'FileSystemDirectoryHandle'. expected true got false`                                                       |
INFO - | `navigator.storage.getDirectory() must reject in a sandboxed iframe.`        | FAIL    | `assert_equals: expected "navigator.storage.getDirectory(): REJECTED: SecurityError" but got "navigator.storage.getDirectory(): EXCEPTION: TypeError"` |
INFO - | `navigator.storage.getDirectory() must reject in a sandboxed opened window.` | FAIL    | `assert_equals: expected "navigator.storage.getDirectory(): REJECTED: SecurityError" but got "navigator.storage.getDirectory(): EXCEPTION: TypeError"` |
INFO - 
INFO - ## Slow tests ##
INFO - 
INFO - |                  Test                 | Result | Longest duration (ms) | Timeout (ms) |
INFO - |---------------------------------------|--------|-----------------------|--------------|
INFO - | `/fs/opaque-origin.https.window.html` | `OK`   | `10689`               | `10000`      |
INFO - 
INFO - ::: Running tests in a loop 10 times : PASS
INFO - ::: Running tests in a loop with restarts 5 times : PASS
INFO - ::: Running tests in a loop 10 times with flags chaos_mode_flags=0xfb : FAIL

Oh, those subtest failures are annotated as expected-failures in https://searchfox.org/mozilla-central/rev/6d7704caae2a61b0101bdb67481a80af20376663/testing/web-platform/meta/fs/opaque-origin.https.window.js.ini

So presumably test-verify is just dutifully printing them because they were indeed test-failures, but they're not unexpected.

So the real issue causing test-verify to fail here is really just the timeout. So, we can close this as a dupe of bug 1734020.

Status: NEW → RESOLVED
Closed: 3 years ago
No longer depends on: tv-chaosmode-timeout-wpt
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.