Closed Bug 1760979 Opened 2 years ago Closed 2 years ago

Intermittent [tier 2] TVw-fis TinderboxPrint: Per-test run of .../overflow-body-propagation-010.html<br/>: FAILURE

Categories

(Core :: Layout: Scrolling and Overflow, defect, P5)

Product:
Core
Component:
Layout: Scrolling and Overflow
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
100 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox98 --- unaffected
firefox99 --- unaffected
firefox100 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jgraham)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Bug 1760979 - Fix wpt verify jobs,
48 bytes, text/x-phabricator-request
Details | Review

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

[task 2022-03-23T09:24:21.064Z] 09:24:21     INFO - TEST-PASS | /css/css-overflow/overflow-body-propagation-010.html | took 137ms
[task 2022-03-23T09:24:21.064Z] 09:24:21     INFO - No more tests
[task 2022-03-23T09:24:21.110Z] 09:24:21     INFO - PID 1281 | 1648027461109	Marionette	INFO	Stopped listening on port 58343
[task 2022-03-23T09:24:21.594Z] 09:24:21     INFO - Browser exited with return code 0
[task 2022-03-23T09:24:21.596Z] 09:24:21     INFO - Closing logging queue
[task 2022-03-23T09:24:21.596Z] 09:24:21     INFO - queue closed
[task 2022-03-23T09:24:21.606Z] 09:24:21     INFO - Got 0 unexpected results, with 0 unexpected passes
[task 2022-03-23T09:24:21.606Z] 09:24:21     INFO - SUITE-END | took 7s
[task 2022-03-23T09:24:21.689Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8002
[task 2022-03-23T09:24:21.690Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8003
[task 2022-03-23T09:24:21.690Z] 09:24:21     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2022-03-23T09:24:21.691Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8446
[task 2022-03-23T09:24:21.691Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8443
[task 2022-03-23T09:24:21.691Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:9000
[task 2022-03-23T09:24:21.691Z] 09:24:21     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2022-03-23T09:24:21.691Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8001
[task 2022-03-23T09:24:21.692Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8445
[task 2022-03-23T09:24:21.692Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8444
[task 2022-03-23T09:24:21.996Z] 09:24:21     INFO - wptserve Stopped http server on 127.0.0.1:8000
[task 2022-03-23T09:24:22.042Z] 09:24:22     INFO - Removed font: Ahem.ttf
[task 2022-03-23T09:24:22.101Z] 09:24:22     INFO - Closing logging queue
[task 2022-03-23T09:24:22.101Z] 09:24:22     INFO - queue closed
[task 2022-03-23T09:24:22.133Z] 09:24:22     INFO - ::: Ran 1 of expected 10 iterations.
[task 2022-03-23T09:24:22.134Z] 09:24:22     INFO - ::: Reached iteration timeout before finishing 2 or more repeat runs.
[task 2022-03-23T09:24:22.134Z] 09:24:22     INFO - ::: At least 2 successful repeat runs are required to validate stability.
[task 2022-03-23T09:24:22.135Z] 09:24:22     INFO - ::: Running tests in a loop 10 times : FAIL
[task 2022-03-23T09:24:22.135Z] 09:24:22     INFO - :::
[task 2022-03-23T09:24:22.136Z] 09:24:22  WARNING - ::: Test verification TIMEOUT
[task 2022-03-23T09:24:22.136Z] 09:24:22     INFO - :::
[task 2022-03-23T09:24:22.357Z] 09:24:22    ERROR - Return code: 1
[task 2022-03-23T09:24:22.358Z] 09:24:22    ERROR - # TBPL FAILURE #
[task 2022-03-23T09:24:22.358Z] 09:24:22  WARNING - setting return code to 2
[task 2022-03-23T09:24:22.358Z] 09:24:22    ERROR - TinderboxPrint: Per-test run of .../overflow-body-propagation-010.html<br/>: FAILURE
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - Running post-action listener: _package_coverage_data
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - Running post-action listener: _resource_record_post_action
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - Running post-action listener: process_java_coverage_data
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - Running post-action listener: stop_device
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - [mozharness: 2022-03-23 09:24:22.359320Z] Finished run-tests step (success)
[task 2022-03-23T09:24:22.359Z] 09:24:22     INFO - Running post-run listener: _resource_record_post_run
[task 2022-03-23T09:24:22.413Z] 09:24:22     INFO - Total resource usage - Wall time: 32s; CPU: 28%; Read bytes: 0; Write bytes: 185434112; Read time: 0; Write time: 8352
[task 2022-03-23T09:24:22.413Z] 09:24:22     INFO - TinderboxPrint: CPU usage<br/>28.3%
[task 2022-03-23T09:24:22.413Z] 09:24:22     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2022-03-23T09:24:22.413Z] 09:24:22     INFO - TinderboxPrint: I/O write bytes / time<br/>185,434,112 / 8,352
[task 2022-03-23T09:24:22.413Z] 09:24:22     INFO - TinderboxPrint: CPU idle<br/>91.6 (71.7%)
[task 2022-03-23T09:24:22.414Z] 09:24:22     INFO - TinderboxPrint: CPU system<br/>4.1 (3.2%)
[task 2022-03-23T09:24:22.414Z] 09:24:22     INFO - TinderboxPrint: CPU user<br/>32.0 (25.1%)
[task 2022-03-23T09:24:22.414Z] 09:24:22     INFO - TinderboxPrint: Swap in / out<br/>0 / 0[task 2022-03-23T09:24:22.414Z] 09:24:22     INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-03-23T09:24:22.414Z] 09:24:22     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-03-23T09:24:22.414Z] 09:24:22     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-03-23T09:24:22.414Z] 09:24:22     INFO - install - Wall time: 12s; CPU: 25%; Read bytes: 0; Write bytes: 51830784; Read time: 0; Write time: 672
[task 2022-03-23T09:24:22.415Z] 09:24:22     INFO - run-tests - Wall time: 21s; CPU: 30%; Read bytes: 0; Write bytes: 133603328; Read time: 0; Write time: 7680
[task 2022-03-23T09:24:22.421Z] 09:24:22  WARNING - returning nonzero exit status 2
[task 2022-03-23T09:24:22.718Z] cleanup
[task 2022-03-23T09:24:22.718Z] + cleanup
[task 2022-03-23T09:24:22.718Z] + local rv=2
[task 2022-03-23T09:24:22.718Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2022-03-23T09:24:22.718Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2022-03-23T09:24:22.719Z] + '[' ']'
[task 2022-03-23T09:24:22.719Z] + true
[task 2022-03-23T09:24:22.719Z] + cleanup_xvfb
[task 2022-03-23T09:24:22.720Z] ++ pidof Xvfb
[task 2022-03-23T09:24:22.723Z] + local xvfb_pid=49
[task 2022-03-23T09:24:22.723Z] + local vnc=false
[task 2022-03-23T09:24:22.723Z] + local interactive=false
[task 2022-03-23T09:24:22.723Z] + '[' -n 49 ']'
[task 2022-03-23T09:24:22.723Z] + [[ false == false ]]
[task 2022-03-23T09:24:22.724Z] + [[ false == false ]]
[task 2022-03-23T09:24:22.724Z] + kill 49
[task 2022-03-23T09:24:22.724Z] + screen -XS xvfb quit
[task 2022-03-23T09:24:22.748Z] + exit 2
[taskcluster 2022-03-23 09:24:23.067Z] === Task Finished ===
[taskcluster 2022-03-23 09:24:25.834Z] Unsuccessful task run with exit code: 2 completed in 113.086 seconds
Regressed by: 1760734
Has Regression Range: --- → yes

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

status-firefox100: --- → affected
status-firefox98: --- → unaffected
status-firefox99: --- → unaffected
status-firefox-esr91: --- → unaffected

:emilio, since you are the author of the regressor, bug 1760734, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(emilio)

The problem with these bugs is that they are not really regressed by bug 1760734. If only it is pre-existing, but since it's TV-specific it only happens when you touch the test.

However, it seems like it's not particularly a test failure... The test is passing, but we fail to start the second run of tests. James / Joel, do you happen to know what might be going on here?

Flags: needinfo?(jmaher)
Flags: needinfo?(james)
Flags: needinfo?(emilio)

Hmm, looks like upstream changes might have regressed my fix for the miscounting of multiple runs without restarts. I'll look at this.

Flags: needinfo?(jmaher)

This was regressed by the changes in PR 32403.

Also add a test to check that we don't regress in the same way
again. This mocks out actually running the tests, which of course
means we aren't testing that we get the right kind of return value
from wptrunner.

Assignee: nobody → james
Status: NEW → ASSIGNED
Failed to create upstream wpt PR due to merge conflicts. This requires fixup from a wpt sync admin.
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/33430 for changes under testing/web-platform/tests

https://hg.mozilla.org/mozilla-central/rev/ce95f2726565

Status: ASSIGNED → RESOLVED
Closed: 2 years ago
status-firefox100: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 100 Branch
Upstream PR merged by moz-wptsync-bot
Flags: needinfo?(james)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: