1547591 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | track.onmute fires before setRemoteDescription resolves. - Test timed out

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=243175545&repo=mozilla-central

https://queue.taskcluster.net/v1/task/BKnZut4pQkSvLlXVe0ZQUQ/runs/0/artifacts/public/logs/live_backing.log

12:59:34 INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() makes stream.onremovetrack fire and the track to be removed from the stream.
12:59:34 INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | stream.onremovetrack fires before setRemoteDescription resolves.
12:59:34 INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() makes track.onmute fire and the track to be muted.
12:59:34 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | track.onmute fires before setRemoteDescription resolves. - Test timed out
12:59:34 INFO -
12:59:34 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() twice is safe. - expected PASS
12:59:34 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | expected OK
12:59:34 INFO - TEST-INFO took 40684ms
12:59:34 INFO - PID 8164 | 1556456374861 Marionette INFO Stopped listening on port 2828
12:59:35 INFO - PID 8164 | [Child 4440: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2275: CloseInt: Closing PeerConnectionImpl f2d3809b1b19fc8c; ending call
12:59:35 INFO - PID 8164 | [Child 4440: Main Thread]: I/jsep [1556456344076000 (id=103079215108 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-setRemoteDescription-tracks.https]: stable -> closed
12:59:35 INFO - PID 8164 | [Child 4440: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2275: CloseInt: Closing PeerConnectionImpl 708c26b03a8c1bbf; ending call
12:59:35 INFO - PID 8164 | [Child 4440: Main Thread]: I/jsep [1556456344079000 (id=103079215108 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-setRemoteDescription-tracks.https]: stable -> closed
12:59:35 INFO - PID 8164 | [Parent 4480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 1984, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 1984, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/[Child 4440, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 4440, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 7424, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 7424, Chrome_ChildThread] [Parent 4480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Parent 4480, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 7624, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [Child 7624, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
12:59:35 INFO - PID 8164 | [GPU 11024, Chrome_ChildThread] WARNING: pipe error: 109: file
12:59:35 INFO - PID 8164 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
12:59:35 INFO - Browser exited with return code 0
12:59:35 INFO - PROCESS LEAKS None
12:59:35 INFO - Closing logging queue
12:59:35 INFO - queue closed
12:59:35 INFO - Setting up ssl

Comment 1

[Intermittent Failures Robot]

1 failures in 3031 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-04-22&endday=2019-04-28&tree=all

Comment 2

[Intermittent Failures Robot]

10 failures in 4131 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 10

Platform breakdown:

• windows10-aarch64: 10

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-04-29&endday=2019-05-05&tree=all

Comment 3

[Intermittent Failures Robot]

7 failures in 4705 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 7

Platform breakdown:

• windows10-aarch64: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-05-06&endday=2019-05-12&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 5

[Daniel Varga [:dvarga]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=254963202&repo=mozilla-central&lineNumber=32942

Comment 6

[Intermittent Failures Robot]

3 failures in 3501 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3

Platform breakdown:

• windows10-aarch64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-07-01&endday=2019-07-07&tree=all

Comment 7

[Intermittent Failures Robot]

6 failures in 4710 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 5

Platform breakdown:

• windows10-64: 1
• windows10-aarch64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 8

[Intermittent Failures Robot]

5 failures in 4298 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5

Platform breakdown:

• windows10-aarch64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-07-15&endday=2019-07-21&tree=all

Comment 9

[Intermittent Failures Robot]

2 failures in 4839 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-08-12&endday=2019-08-18&tree=all

Comment 10

[Intermittent Failures Robot]

4 failures in 5047 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3
• try: 1

Platform breakdown:

• windows10-aarch64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 11

[Intermittent Failures Robot]

2 failures in 4408 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 12

[Intermittent Failures Robot]

2 failures in 4350 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 13

[Intermittent Failures Robot]

2 failures in 4292 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 3964 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 15

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 16

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=299166093&repo=mozilla-central&lineNumber=35788

...
[task 2020-04-24T03:58:39.586Z] 03:58:39     INFO - PID 1644 | [Child 4244: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 1ead4602eb0fe6b8
[task 2020-04-24T03:58:39.586Z] 03:58:39     INFO - PID 1644 | Timecard created 1587700709.537000
[task 2020-04-24T03:58:39.587Z] 03:58:39     INFO - PID 1644 |  Timestamp   | Delta       | Event                  | File                         | Function
[task 2020-04-24T03:58:39.587Z] 03:58:39     INFO - PID 1644 | ==========================================================================================================
[task 2020-04-24T03:58:39.587Z] 03:58:39     INFO - PID 1644 |     0.000000 |    0.000000 | Constructor Completed  | PeerConnectionImpl.cpp:326   | PeerConnectionImpl
[task 2020-04-24T03:58:39.587Z] 03:58:39     INFO - PID 1644 |     0.000000 |    0.000000 | Initializing PC Ctx    | PeerConnectionImpl.cpp:452   | Initialize
[task 2020-04-24T03:58:39.588Z] 03:58:39     INFO - PID 1644 |     0.034000 |    0.034000 | Set Remote Description | PeerConnectionImpl.cpp:1383  | SetRemoteDescription
[task 2020-04-24T03:58:39.589Z] 03:58:39     INFO - PID 1644 |    10.040000 |   10.006000 | Destructor Invoked     | PeerConnectionImpl.cpp:335   | ~PeerConnectionImpl
[task 2020-04-24T03:58:39.589Z] 03:58:39     INFO - PID 1644 | [Child 4244: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 7a8dbb2ea11deda3
[task 2020-04-24T03:58:39.590Z] 03:58:39     INFO - PID 1644 | Timecard created 1587700709.533000
[task 2020-04-24T03:58:39.590Z] 03:58:39     INFO - PID 1644 |  Timestamp   | Delta       | Event                 | File                         | Function
[task 2020-04-24T03:58:39.590Z] 03:58:39     INFO - PID 1644 | ========================================================================================================
[task 2020-04-24T03:58:39.591Z] 03:58:39     INFO - PID 1644 |     0.000000 |    0.000000 | Constructor Completed | PeerConnectionImpl.cpp:326   | PeerConnectionImpl
[task 2020-04-24T03:58:39.591Z] 03:58:39     INFO - PID 1644 |     0.001000 |    0.001000 | Initializing PC Ctx   | PeerConnectionImpl.cpp:452   | Initialize
[task 2020-04-24T03:58:39.592Z] 03:58:39     INFO - PID 1644 |     0.026000 |    0.025000 | Create Offer          | PeerConnectionImpl.cpp:1227  | CreateOffer
[task 2020-04-24T03:58:39.592Z] 03:58:39     INFO - PID 1644 |     0.035000 |    0.009000 | Set Local Description | PeerConnectionImpl.cpp:1296  | SetLocalDescription
[task 2020-04-24T03:58:39.592Z] 03:58:39     INFO - PID 1644 |    10.050000 |   10.015000 | Destructor Invoked    | PeerConnectionImpl.cpp:335   | ~PeerConnectionImpl
[task 2020-04-24T03:58:39.593Z] 03:58:39     INFO - PID 1644 | [Child 4244: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for b2bdbce8eb81473b
[task 2020-04-24T03:58:39.621Z] 03:58:39     INFO - 
[task 2020-04-24T03:58:39.622Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | ontrack fires before setRemoteDescription resolves. 
[task 2020-04-24T03:58:39.622Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | addTrack() with two tracks and one stream makes ontrack fire twice with the tracks and shared stream. 
[task 2020-04-24T03:58:39.622Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | addTrack() for an existing stream makes stream.onaddtrack fire. 
[task 2020-04-24T03:58:39.622Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | stream.onaddtrack fires before setRemoteDescription resolves. 
[task 2020-04-24T03:58:39.622Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | addTrack() with a track and two streams makes ontrack fire with a track and two streams. 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | ontrack's receiver matches getReceivers(). 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() does not remove the receiver. 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() makes stream.onremovetrack fire and the track to be removed from the stream. 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | stream.onremovetrack fires before setRemoteDescription resolves. 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() makes track.onmute fire and the track to be muted. 
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | track.onmute fires before setRemoteDescription resolves. - Test timed out
[task 2020-04-24T03:58:39.623Z] 03:58:39     INFO - 
[task 2020-04-24T03:58:39.624Z] 03:58:39     INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() twice is safe. - expected PASS
[task 2020-04-24T03:58:39.624Z] 03:58:39     INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | expected OK
[task 2020-04-24T03:58:39.624Z] 03:58:39     INFO - TEST-INFO took 10325ms

Comment 17

[Intermittent Failures Robot]

1 failures in 5582 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 18

[Intermittent Failures Robot]

1 failures in 5423 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 19

[Intermittent Failures Robot]

1 failures in 5488 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 20

[Intermittent Failures Robot]

1 failures in 4727 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1547591&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 21

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.