1633533 - Intermittent dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | Test timed out.

Reporter

Description

•

4 years ago

treeherder

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=299618584&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aNI5GmQyRC6pMGoFODiqew/runs/0/artifacts/public/logs/live_backing.log

[task 2020-04-27T19:26:56.375Z] 19:26:56 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | PeerConnectionWrapper (pcRemote): legal signaling state transition from have-remote-offer to stable
[task 2020-04-27T19:26:56.375Z] 19:26:56 INFO - PeerConnectionWrapper (pcRemote): Successfully set remote description
[task 2020-04-27T19:26:56.376Z] 19:26:56 INFO - PeerConnectionWrapper (pcRemote): Checking local tracks [{"track":{},"sender":{},"streamId":"{7f7a7a99-4fb1-42e9-9958-c8de4ec27429}"}]
[task 2020-04-27T19:26:56.377Z] 19:26:56 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | The number of senders with a track should be equal to the number of expected local tracks.
[task 2020-04-27T19:26:56.378Z] 19:26:56 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | Sender 0 should match
[task 2020-04-27T19:26:56.378Z] 19:26:56 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | Track 0 should match
[task 2020-04-27T19:26:56.378Z] 19:26:56 INFO - Run step 66: PC_LOCAL_ROLLBACK
[task 2020-04-27T19:26:56.379Z] 19:26:56 INFO - PeerConnectionWrapper (pcLocal): "onsignalingstatechange" event fired
[task 2020-04-27T19:26:56.379Z] 19:26:56 INFO - PeerConnectionWrapper (pcLocal): 'signalingstatechange' event received
[task 2020-04-27T19:26:56.380Z] 19:26:56 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | PeerConnectionWrapper (pcLocal): legal signaling state transition from have-local-offer to stable
[task 2020-04-27T19:26:56.380Z] 19:26:56 INFO - PeerConnectionWrapper (pcLocal): Successfully set the local description
[task 2020-04-27T19:26:56.381Z] 19:26:56 INFO - Run step 67: PC_LOCAL_WAIT_FOR_END_OF_TRICKLE
[task 2020-04-27T19:26:56.390Z] 19:26:56 INFO - Buffered messages finished
[task 2020-04-27T19:26:56.392Z] 19:26:56 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | Test timed out.
[task 2020-04-27T19:26:56.392Z] 19:26:56 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:299:16
[task 2020-04-27T19:26:56.392Z] 19:26:56 INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:128:22
[task 2020-04-27T19:26:56.393Z] 19:26:56 INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:150:18
[task 2020-04-27T19:26:56.393Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.393Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.393Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.394Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.394Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.394Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.394Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.395Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.395Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.395Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.396Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.397Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.397Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.397Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:184:15
[task 2020-04-27T19:26:56.398Z] 19:26:56 INFO - TestRunner.runTests/<@https://example.com/tests/SimpleTest/TestRunner.js:420:16
[task 2020-04-27T19:26:56.399Z] 19:26:56 INFO - promise callbackTestRunner.runTests@https://example.com/tests/SimpleTest/TestRunner.js:407:48
[task 2020-04-27T19:26:56.399Z] 19:26:56 INFO - RunSet.runtests@https://example.com/tests/SimpleTest/setup.js:218:14
[task 2020-04-27T19:26:56.399Z] 19:26:56 INFO - RunSet.runall@https://example.com/tests/SimpleTest/setup.js:197:12
[task 2020-04-27T19:26:56.399Z] 19:26:56 INFO - hookupTests@https://example.com/tests/SimpleTest/setup.js:294:12
[task 2020-04-27T19:26:56.400Z] 19:26:56 INFO - parseTestManifest@https://example.com/manifestLibrary.js:46:13
[task 2020-04-27T19:26:56.400Z] 19:26:56 INFO - getTestManifest/req.onload@https://example.com/manifestLibrary.js:59:28
[task 2020-04-27T19:26:56.400Z] 19:26:56 INFO - EventHandlerNonNullgetTestManifest@https://example.com/manifestLibrary.js:55:3
[task 2020-04-27T19:26:56.400Z] 19:26:56 INFO - hookup@https://example.com/tests/SimpleTest/setup.js:270:20
[task 2020-04-27T19:26:56.400Z] 19:26:56 INFO - EventHandlerNonNull*@https://example.com/tests/dom/media/tests/mochitest?autorun=1&closeWhenDone=1&consoleLevel=INFO&testname=tests/dom/media/tests/mochitest/mochitest.ini&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2020-04-27T19:26:57.027Z] 19:26:57 INFO - GECKO(1830) | [Child 1883: Socket Thread]: E/signaling [Socket Thread|WebrtcAudioSessionConduit] AudioConduit.cpp:225: GetRTCPReceiverReport for AudioConduit:0x7f97de514000 RTT returned an error after previously succeeding.
[task 2020-04-27T19:26:57.089Z] 19:26:57 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2020-04-27T19:26:57.094Z] 19:26:57 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2020-04-27T19:26:57.110Z] 19:26:57 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2020-04-27T19:26:57.111Z] 19:26:57 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2020-04-27T19:26:57.112Z] 19:26:57 INFO - GECKO(1830) | MEMORY STAT | vsize 2916MB | residentFast 241MB | heapAllocated 37MB
[task 2020-04-27T19:26:57.148Z] 19:26:57 INFO - TEST-OK | dom/media/tests/mochitest/test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html | took 319654ms

Andreea Pavel [:apavel]

Updated

•

4 years ago

Regressed by: 1629565

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Keywords: regression

Andreea Pavel [:apavel]

Comment 1

•

4 years ago

Byron can you take a look?

There are 27 total failures since the 26th on linux1804-64 and linux1804-64-qr debug, macosx1014-64 debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=300220579&repo=autoland&lineNumber=47008

Flags: needinfo?(docfaraday)

Andreea Pavel [:apavel]

Updated

•

4 years ago

Whiteboard: [stockwell needswork:owner]

Byron Campen [:bwc]

Assignee

Comment 2

•

4 years ago

This is probably a flaw in the test case, but I will figure it out.

Assignee: nobody → docfaraday

Flags: needinfo?(docfaraday)

Priority: P5 → P2

Byron Campen [:bwc]

Assignee

Comment 3

•

4 years ago

Attached file Bug 1633533: In these tests, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'. — Details

Byron Campen [:bwc]

Assignee

Comment 4

•

4 years ago

Seems to clear up the unreliability on my own hardware. Checking on try.

Byron Campen [:bwc]

Assignee

Comment 5

•

4 years ago

https://treeherder.mozilla.org/#/jobs?repo=try&revision=0475cfbcb25118752bfdbe2deca35d411ea70010

Byron Campen [:bwc]

Assignee

Updated

•

4 years ago

Comment 6

•

4 years ago

The bug title and description here are for different tests, actually. The description here matches bug 1633595, although it may be that test_peerConnection_restartIceLocalAndRemoteRollbackNoSubsequentRestart.html is also timing out intermittently.

Byron Campen [:bwc]

Assignee

Comment 7

•

4 years ago

https://treeherder.mozilla.org/#/jobs?repo=try&revision=21de4867c4e80c08181cd61ef5ebbcd2a15899ac

Phabricator Automation

Updated

•

4 years ago

Attachment #9144756 - Attachment description: Bug 1633533: In this test, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'. → Bug 1633533: In these tests, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'.

Byron Campen [:bwc]

Assignee

Comment 8

•

4 years ago

Try runs for both tests look good.

BugBot [:suhaib / :marco/ :calixte]

Comment 9

•

4 years ago

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

status-firefox75: --- → unaffected

status-firefox76: --- → unaffected

status-firefox77: --- → affected

Comment hidden (Intermittent Failures Robot)

Byron Campen [:bwc]

Assignee

Comment 14

•

4 years ago

This is really frequent, so we should fix this ASAP.

Priority: P2 → P1

Comment hidden (Intermittent Failures Robot)

Pulsebot

Comment 16

•

4 years ago

Pushed by bcampen@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/2d78a4c42092
In these tests, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'. r=jib

Comment hidden (Intermittent Failures Robot)

Raul Gurzau (:RaulG)

Comment 18

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/2d78a4c42092

Status: NEW → RESOLVED

Closed: 4 years ago

status-firefox78: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla78

Andreea Pavel [:apavel]

Comment 19

•

4 years ago

Still occurs on mozilla-beta: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=301259775&repo=mozilla-beta&lineNumber=39382 should the fix be uplifted?

Flags: needinfo?(docfaraday)

Byron Campen [:bwc]

Assignee

Comment 20

•

4 years ago

Yeah, it seems to be working, so let's uplift this. Request coming.

Flags: needinfo?(docfaraday)

Byron Campen [:bwc]

Assignee

Comment 21

•

4 years ago

Comment on attachment 9144756 [details]
Bug 1633533: In these tests, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'.

Beta/Release Uplift Approval Request

User impact if declined: Lots of intermittent failures on try.
Is this code covered by automated tests?: Yes
Has the fix been verified in Nightly?: Yes
Needs manual test from QE?: No
If yes, steps to reproduce:
List of other uplifts needed: None
Risk to taking this patch: Low
Why is the change risky/not risky? (and alternatives if risky): Just test fixes.
String changes made/needed: None.

Attachment #9144756 - Flags: approval-mozilla-beta?

Ryan VanderMeulen [:RyanVM]

Comment 22

•

4 years ago

Comment on attachment 9144756 [details]
Bug 1633533: In these tests, wait for gathering to finish before rolling back, to ensure a transition back to 'gathering'.

test-only patches don't need approval for uplift

Attachment #9144756 - Flags: approval-mozilla-beta?

Ryan VanderMeulen [:RyanVM]

Comment 23

•

4 years ago

bugherder uplift

https://hg.mozilla.org/releases/mozilla-beta/rev/52927952ec71

status-firefox77: affected → fixed

Flags: in-testsuite+

Comment hidden (Intermittent Failures Robot)