Closed Bug 1633563 Opened 5 years ago Closed 5 years ago

Intermittent TinderboxPrint: Per-test run of .../tests/webrtc/<test><br/>: FAILURE

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

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


[task 2020-04-27T20:11:27.021Z] 20:11:27 INFO - TEST-OK | /webrtc/RTCPeerConnection-iceGatheringState.html | took 8322ms
[task 2020-04-27T20:11:27.022Z] 20:11:27 INFO - No more tests
[task 2020-04-27T20:11:27.029Z] 20:11:27 INFO - PID 3072 | 1588018287012 Marionette INFO Stopped listening on port 49382
[task 2020-04-27T20:11:27.110Z] 20:11:27 INFO - PID 3072 | [Child 5064: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2633: GetStats: Found no pipelines matching selector.
[task 2020-04-27T20:11:27.111Z] 20:11:27 INFO - PID 3072 | [Child 5064: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2022: CloseInt: Closing PeerConnectionImpl 9f77ea5c4c78c3b6; ending call
[task 2020-04-27T20:11:27.111Z] 20:11:27 INFO - PID 3072 | [Child 5064: Main Thread]: I/jsep [1588018285106000 (id=27917287426 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-iceGatheringState.html)]: stable -> closed
[task 2020-04-27T20:11:33.230Z] 20:11:33 INFO - PID 3072 | [Parent 3560, Gecko_IOThread] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc, line 166
[task 2020-04-27T20:11:33.437Z] 20:11:33 INFO - Browser exited with return code 0
[task 2020-04-27T20:11:33.437Z] 20:11:33 INFO - PROCESS LEAKS None
[task 2020-04-27T20:11:33.437Z] 20:11:33 INFO - Closing logging queue
[task 2020-04-27T20:11:33.437Z] 20:11:33 INFO - queue closed
[task 2020-04-27T20:11:33.438Z] 20:11:33 INFO - PROCESS LEAKS None
[task 2020-04-27T20:11:33.505Z] 20:11:33 INFO - Got 0 unexpected results
[task 2020-04-27T20:11:33.505Z] 20:11:33 INFO - SUITE-END | took 89s
[task 2020-04-27T20:11:33.548Z] 20:11:33 INFO - Closing logging queue
[task 2020-04-27T20:11:33.549Z] 20:11:33 INFO - queue closed
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO - ## All results ##
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO -
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO - ### /webrtc/RTCPeerConnection-iceGatheringState.html ###
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO - | Subtest | Results | Messages |
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO - |------------------------------------------------------------------------------------------------------------------------------|---------|------------------------------------------------------------|
[task 2020-04-27T20:11:33.636Z] 20:11:33 INFO - | | OK | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | Initial iceGatheringState should be new | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | iceGatheringState should eventually become complete after setLocalDescription | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | setLocalDescription(reoffer) with no new transports should not cause iceGatheringState to change | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | rolling back an ICE restart when gathering is complete should not result in iceGatheringState changes | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | setLocalDescription(reoffer) with a new transport should cause iceGatheringState to go to "checking" and then "complete" | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | sRD does not cause ICE gathering state changes | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | setLocalDescription(rollback) of original offer should cause iceGatheringState to reach "new" when starting in "complete" | PASS | |
[task 2020-04-27T20:11:33.637Z] 20:11:33 INFO - | setLocalDescription(rollback) of original offer should cause iceGatheringState to reach "new" when starting in "gathering" | PASS | |
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - | renegotiation that closes all transports should result in ICE gathering state "new" | PASS | |
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - | connection with one data channel should eventually have connected connection state | FAIL | can't access property "transport", pc2.sctp is undefined |
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO -
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - ## Slow tests ##
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO -
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - | Test | Result | Longest duration (ms) | Timeout (ms) |
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - |----------------------------------------------------|--------|-----------------------|--------------|
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO - | /webrtc/RTCPeerConnection-iceGatheringState.html | OK | 9321 | 10000 |
[task 2020-04-27T20:11:33.638Z] 20:11:33 INFO -
[task 2020-04-27T20:11:33.639Z] 20:11:33 INFO - ::: Running tests in a loop 10 times : FAIL
[task 2020-04-27T20:11:33.639Z] 20:11:33 INFO - :::
[task 2020-04-27T20:11:33.639Z] 20:11:33 ERROR - ::: Test verification FAIL
[task 2020-04-27T20:11:33.639Z] 20:11:33 INFO - :::
[task 2020-04-27T20:11:33.731Z] 20:11:33 ERROR - Return code: 1
[task 2020-04-27T20:11:33.732Z] 20:11:33 ERROR - # TBPL FAILURE #

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Flags: needinfo?(dminor)
Whiteboard: [stockwell needswork:owner]

So part of the problem here is that it appears the bug name "Test verification FAIL" will match any wpt test that fails on test verification.

There are webrtc test failures here, but also things like:

TinderboxPrint: Per-test run of .../2d.text.measure.actualBoundingBox.html<br/>: FAILURE
TinderboxPrint: Per-test run of .../feature-policy-header-policy-disallowed-for-all.https.sub.html<br/>: FAILURE
TinderboxPrint: Per-test run of .../cookieListItem_attributes.tentative.https.any.js<br/>: FAILURE
TinderboxPrint: Per-test run of .../require-corp-about-blank.html<br/>: FAILURE

etc.

It looks like the most common webrtc related failure is:

TinderboxPrint: Per-test run of .../tests/webrtc/simplecall.https.html<br/>: FAILURE

but that's far from the majority of the failures being tracked by this bug. Byron has been looking at our wpt intermittents, so I'm going to needinfo him, but I think the first step is to rename this so at the very least only WebRTC related stuff ends up in here. I think one bug per failing test would be more useful.

Flags: needinfo?(dminor) → needinfo?(docfaraday)

Rate seems to be way down.

Flags: needinfo?(docfaraday)
Summary: Intermittent ::: Test verification FAIL → Intermittent TinderboxPrint: Per-test run of .../tests/webrtc/<test><br/>: FAILURE
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.