Closed Bug 1754455 Opened 3 years ago Closed 3 years ago

Intermittent TVw-fis TinderboxPrint: Per-test run of .../opaque-origin.html<br/>: FAILURE

Categories

(Core :: DOM: postMessage, defect, P5)

defect

Tracking

()

RESOLVED FIXED
99 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox97 --- unaffected
firefox98 --- unaffected
firefox99 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

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


[task 2022-02-09T13:20:04.289Z] 13:20:04     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-02-09T13:20:04.291Z] 13:20:04     INFO - Closing logging queue
[task 2022-02-09T13:20:04.291Z] 13:20:04     INFO - queue closed
[task 2022-02-09T13:20:04.292Z] 13:20:04  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2022-02-09T13:20:04.305Z] 13:20:04     INFO - Got 0 unexpected results, with 0 unexpected passes
[task 2022-02-09T13:20:04.306Z] 13:20:04     INFO - SUITE-END | took 17s
[task 2022-02-09T13:20:04.367Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8001
[task 2022-02-09T13:20:04.380Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8002
[task 2022-02-09T13:20:04.381Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:9000
[task 2022-02-09T13:20:04.382Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8003
[task 2022-02-09T13:20:04.382Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8446
[task 2022-02-09T13:20:04.383Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8445
[task 2022-02-09T13:20:04.391Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8443
[task 2022-02-09T13:20:04.392Z] 13:20:04     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2022-02-09T13:20:04.392Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8444
[task 2022-02-09T13:20:04.393Z] 13:20:04     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2022-02-09T13:20:04.624Z] 13:20:04     INFO - wptserve Stopped http server on 127.0.0.1:8000
[task 2022-02-09T13:20:04.672Z] 13:20:04     INFO - Removed font: Ahem.ttf
[task 2022-02-09T13:20:04.732Z] 13:20:04     INFO - Closing logging queue
[task 2022-02-09T13:20:04.733Z] 13:20:04     INFO - queue closed
[task 2022-02-09T13:20:04.738Z] 13:20:04     INFO - ::: Reached iteration timeout before finishing 2 or more repeat runs.
[task 2022-02-09T13:20:04.739Z] 13:20:04     INFO - ::: At least 2 successful repeat runs are required to validate stability.
[task 2022-02-09T13:20:04.739Z] 13:20:04     INFO - ::: Running tests in a loop 10 times : FAIL
[task 2022-02-09T13:20:04.739Z] 13:20:04     INFO - :::
[task 2022-02-09T13:20:04.739Z] 13:20:04  WARNING - ::: Test verification TIMEOUT
[task 2022-02-09T13:20:04.740Z] 13:20:04     INFO - :::
[task 2022-02-09T13:20:05.001Z] 13:20:05    ERROR - Return code: 1
[task 2022-02-09T13:20:05.002Z] 13:20:05    ERROR - # TBPL FAILURE #
[task 2022-02-09T13:20:05.002Z] 13:20:05  WARNING - setting return code to 2
[task 2022-02-09T13:20:05.002Z] 13:20:05    ERROR - TinderboxPrint: Per-test run of .../opaque-origin.html<br/>: FAILURE
[task 2022-02-09T13:20:05.002Z] 13:20:05     INFO - Running post-action listener: _package_coverage_data
[task 2022-02-09T13:20:05.002Z] 13:20:05     INFO - Running post-action listener: _resource_record_post_action
[task 2022-02-09T13:20:05.003Z] 13:20:05     INFO - Running post-action listener: process_java_coverage_data
[task 2022-02-09T13:20:05.003Z] 13:20:05     INFO - Running post-action listener: stop_device
[task 2022-02-09T13:20:05.003Z] 13:20:05     INFO - [mozharness: 2022-02-09 13:20:05.002693Z] Finished run-tests step (success)
[task 2022-02-09T13:20:05.003Z] 13:20:05     INFO - Running post-run listener: _resource_record_post_run
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - Total resource usage - Wall time: 45s; CPU: 37%; Read bytes: 8192; Write bytes: 775565312; Read time: 0; Write time: 108976
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - TinderboxPrint: CPU usage<br/>36.8%
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - TinderboxPrint: I/O read bytes / time<br/>8,192 / 0
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - TinderboxPrint: I/O write bytes / time<br/>775,565,312 / 108,976
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - TinderboxPrint: CPU idle<br/>111.4 (62.1%)
[task 2022-02-09T13:20:05.166Z] 13:20:05     INFO - TinderboxPrint: CPU iowait<br/>2.2 (1.2%)
[task 2022-02-09T13:20:05.167Z] 13:20:05     INFO - TinderboxPrint: CPU system<br/>5.1 (2.8%)
[task 2022-02-09T13:20:05.167Z] 13:20:05     INFO - TinderboxPrint: CPU user<br/>60.7 (33.9%)
[task 2022-02-09T13:20:05.167Z] 13:20:05     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2022-02-09T13:20:05.167Z] 13:20:05     INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-02-09T13:20:05.167Z] 13:20:05     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-02-09T13:20:05.167Z] 13:20:05     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-02-09T13:20:05.167Z] 13:20:05     INFO - install - Wall time: 15s; CPU: 25%; Read bytes: 0; Write bytes: 528384; Read time: 0; Write time: 20
[task 2022-02-09T13:20:05.168Z] 13:20:05     INFO - run-tests - Wall time: 31s; CPU: 43%; Read bytes: 8192; Write bytes: 775036928; Read time: 0; Write time: 108956
[task 2022-02-09T13:20:05.176Z] 13:20:05  WARNING - returning nonzero exit status 2

Set release status flags based on info from the regressing bug 1754139

Has Regression Range: --- → yes
Flags: needinfo?(bugs)

This happened only 2022-02-09 and the error didn't seem to have anything to do with bug 1754139

Flags: needinfo?(bugs)

Yes, this seems just to be a random slow execution to me.

Keywords: regression
No longer regressed by: 1754139

Bug 1754888 fixed this false TVw intermittent. (Bugzilla doesn't seem to support "resolve as duplicate" when changing multiple bugs at once.)

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 99 Branch
You need to log in before you can comment on or make changes to this bug.