Intermittent /tests/dom/media/tests/mochitest/test_peerConnection_nonDefaultRate.html logged result after SimpleTest.finish(): pc1 addIceCandidate success
Categories
(Core :: WebRTC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Assigned: mjf)
References
(Regression)
Details
(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])
Attachments
(1 file)
Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=295857921&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Tgrrbo48TzSyr8IaT-MOxA/runs/0/artifacts/public/logs/live_backing.log
[task 2020-04-02T00:01:58.646Z] 00:01:58 INFO - TEST-OK | dom/media/tests/mochitest/test_peerConnection_nonDefaultRate.html | took 479ms
[task 2020-04-02T00:01:58.647Z] 00:01:58 ERROR - /tests/dom/media/tests/mochitest/test_peerConnection_nonDefaultRate.html logged result after SimpleTest.finish(): pc1 addIceCandidate success
[task 2020-04-02T00:01:58.649Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2022: CloseInt: Closing PeerConnectionImpl 852048e767521b4e; ending call
[task 2020-04-02T00:01:58.650Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: I/jsep [1585785718254294 (id=2147484116 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_nonDefaultRate.html]: stable -> closed
[task 2020-04-02T00:01:58.650Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2620: GetStats: Found no pipelines matching selector.
[task 2020-04-02T00:01:58.652Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2022: CloseInt: Closing PeerConnectionImpl 360860441bcac375; ending call
[task 2020-04-02T00:01:58.653Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: I/jsep [1585785718258510 (id=2147484116 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_nonDefaultRate.html]: stable -> closed
[task 2020-04-02T00:01:58.654Z] 00:01:58 INFO - TEST-START | dom/media/tests/mochitest/test_peerConnection_offerRequiresReceiveAudio.html
[task 2020-04-02T00:01:58.710Z] 00:01:58 INFO - GECKO(4009) | TEST DEVICES: Got loopback audio: Monitor of Null Output
[task 2020-04-02T00:01:58.711Z] 00:01:58 INFO - GECKO(4009) | TEST DEVICES: Got loopback video: Dummy video device (0x0000)
[task 2020-04-02T00:01:58.747Z] 00:01:58 INFO - GECKO(4009) | Timecard created 1585785718.253931
[task 2020-04-02T00:01:58.747Z] 00:01:58 INFO - GECKO(4009) | Timestamp | Delta | Event | File | Function
[task 2020-04-02T00:01:58.748Z] 00:01:58 INFO - GECKO(4009) | ======================================================================================================================
[task 2020-04-02T00:01:58.748Z] 00:01:58 INFO - GECKO(4009) | 0.000087 | 0.000087 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2020-04-02T00:01:58.749Z] 00:01:58 INFO - GECKO(4009) | 0.000379 | 0.000292 | Initializing PC Ctx | PeerConnectionImpl.cpp:452 | Initialize
[task 2020-04-02T00:01:58.750Z] 00:01:58 INFO - GECKO(4009) | 0.058721 | 0.058342 | Create Offer | PeerConnectionImpl.cpp:1227 | CreateOffer
[task 2020-04-02T00:01:58.751Z] 00:01:58 INFO - GECKO(4009) | 0.074446 | 0.015725 | Set Local Description | PeerConnectionImpl.cpp:1296 | SetLocalDescription
[task 2020-04-02T00:01:58.752Z] 00:01:58 INFO - GECKO(4009) | 0.089026 | 0.014580 | Set Remote Description | PeerConnectionImpl.cpp:1383 | SetRemoteDescription
[task 2020-04-02T00:01:58.753Z] 00:01:58 INFO - GECKO(4009) | 0.096514 | 0.007488 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2424 | IceGatheringStateChange
[task 2020-04-02T00:01:58.754Z] 00:01:58 INFO - GECKO(4009) | 0.127139 | 0.030625 | Ice gathering state: complete | PeerConnectionImpl.cpp:2427 | IceGatheringStateChange
[task 2020-04-02T00:01:58.755Z] 00:01:58 INFO - GECKO(4009) | 0.127972 | 0.000833 | Add Ice Candidate | PeerConnectionImpl.cpp:1521 | AddIceCandidate
[task 2020-04-02T00:01:58.759Z] 00:01:58 INFO - GECKO(4009) | 0.147687 | 0.019715 | Add Ice Candidate | PeerConnectionImpl.cpp:1521 | AddIceCandidate
[task 2020-04-02T00:01:58.760Z] 00:01:58 INFO - GECKO(4009) | 0.159423 | 0.011736 | Add Ice Candidate | PeerConnectionImpl.cpp:1521 | AddIceCandidate
[task 2020-04-02T00:01:58.761Z] 00:01:58 INFO - GECKO(4009) | 0.163231 | 0.003808 | Ice state: checking | PeerConnectionImpl.cpp:2369 | IceConnectionStateChange
[task 2020-04-02T00:01:58.762Z] 00:01:58 INFO - GECKO(4009) | 0.163426 | 0.000195 | Ice state: connected | PeerConnectionImpl.cpp:2372 | IceConnectionStateChange
[task 2020-04-02T00:01:58.762Z] 00:01:58 INFO - GECKO(4009) | 0.173008 | 0.009582 | Add Ice Candidate | PeerConnectionImpl.cpp:1521 | AddIceCandidate
[task 2020-04-02T00:01:58.763Z] 00:01:58 INFO - GECKO(4009) | 0.180325 | 0.007317 | Add Ice Candidate | PeerConnectionImpl.cpp:1521 | AddIceCandidate
[task 2020-04-02T00:01:58.764Z] 00:01:58 INFO - GECKO(4009) | 0.485280 | 0.304955 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2020-04-02T00:01:58.765Z] 00:01:58 INFO - GECKO(4009) | [Child 4086: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 852048e767521b4e
[task 2020-04-02T00:01:58.766Z] 00:01:58 INFO - GECKO(4009) | Timecard created 1585785718.258250
Comment 1•4 years ago
|
||
Retriggered:
Regressed by https://hg.mozilla.org/integration/autoland/rev/db8ff01085b662b25b2b427e7f0dfe6edb5945e1
:dminor can you take a look?
Comment hidden (Intermittent Failures Robot) |
Comment 3•4 years ago
|
||
Sorry, I'm not familiar with our js engine.
Comment 4•4 years ago
|
||
I don't see how bug 1625734 could have any effect on that test case. (Bug 1625734 only effects memory reporting for BigInt values which are nursery allocated.)
:aciure, can you try re-run the tests one more time to see if a different regression bug shows up? For example one of the other bugs visible in the try-runs from comment #1, is bug 1609124, which seems to be a much more likely regressor, at least based on the commit message.
Thanks,
André
Comment 5•4 years ago
|
||
Ran more retriggers and you were right, bug 1609124 is the culprit.
:mjf can you take a look?
Updated•4 years ago
|
Assignee | ||
Comment 6•4 years ago
|
||
Now that we're getting both IPv4 and IPv6 local addrs, it looks like some of the candidates are coming in after the test is finished. I'll take a look.
Assignee | ||
Comment 7•4 years ago
|
||
Assignee | ||
Comment 8•4 years ago
|
||
Pushed by mfroman@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/4a3f36c9e056 Close peer connections when test_peerConnection_nonDefaultRate.html is finished. r=bwc
Comment 10•4 years ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 13•4 years ago
|
||
bugherder uplift |
Comment hidden (Intermittent Failures Robot) |
Description
•