1607924 - 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]

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

---

[task 2020-01-08T20:52:09.358Z] 20:52:09 INFO - TEST-START | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html
[task 2020-01-08T20:52:09.361Z] 20:52:09 INFO - Clearing pref media.navigator.permission.disabled
[task 2020-01-08T20:52:09.368Z] 20:52:09 INFO - PID 5308 | [Child 5772: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1578516727886000 (id=118111600642 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html transport-id=transport_1', no candidates.
[task 2020-01-08T20:52:09.371Z] 20:52:09 INFO - Clearing pref privacy.resistFingerprinting.reduceTimerPrecision.jitter
[task 2020-01-08T20:52:09.373Z] 20:52:09 INFO - PID 5308 | [Child 5772: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:69: OnStunAddrsAvailable: receiving (4) stun addrs
[task 2020-01-08T20:52:09.394Z] 20:52:09 INFO - Clearing pref media.navigator.streams.fake
[task 2020-01-08T20:52:09.408Z] 20:52:09 INFO - PID 5308 | (ice/ERR) ICE(PC:1578516727886000 (id=118111600642 url=http://web-platform.test:8000/webrtc/RTCPeer[Child 5772: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0000025D351B9F20
[task 2020-01-08T20:52:09.410Z] 20:52:09 INFO - Clearing pref privacy.reduceTimerPrecision
[task 2020-01-08T20:52:09.443Z] 20:52:09 INFO - PID 5308 | Connection-setRemoteDescription-rollback.html): peer (PC:157851672788[Child 5772: Main Thread]: I/jsep [1578516729129000 (id=118111600642 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html]: stable -> have-remote-offer
[task 2020-01-08T20:52:09.446Z] 20:52:09 INFO - Setting pref media.navigator.permission.disabled (true)
[task 2020-01-08T20:52:09.488Z] 20:52:09 INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter (false)
[task 2020-01-08T20:52:09.495Z] 20:52:09 INFO - PID 5308 | 6000 (id=118111600642 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html:default) pairing local trickle ICE candidate host(IP4:10.7.204.34:58[Child 5772: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1454: SetRemoteDescription: pc = 8b4ba01ab0bb1e32, asking JS to create transceiver
[task 2020-01-08T20:52:09.496Z] 20:52:09 INFO - PID 5308 | 035/TCP) active
[task 2020-01-08T20:52:09.520Z] 20:52:09 INFO - Setting pref media.navigator.streams.fake (true)
[task 2020-01-08T20:52:09.541Z] 20:52:09 INFO - PID 5308 | [Child 5772: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1578516727886000 (id=118111600642 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html transport-id=transport_1', no candidates.
[task 2020-01-08T20:52:09.548Z] 20:52:09 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-01-08T20:52:09.570Z] 20:52:09 INFO - Closing window 133

[task 2020-01-08T20:52:50.126Z] 20:52:50 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-01-08T20:52:50.127Z] 20:52:50 INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | stream.onremovetrack fires before setRemoteDescription resolves.
[task 2020-01-08T20:52:50.128Z] 20:52:50 INFO - TEST-PASS | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() makes track.onmute fire and the track to be muted.
[task 2020-01-08T20:52:50.128Z] 20:52:50 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | track.onmute fires before setRemoteDescription resolves. - Test timed out
[task 2020-01-08T20:52:50.131Z] 20:52:50 INFO -
[task 2020-01-08T20:52:50.131Z] 20:52:50 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | removeTrack() twice is safe. - expected PASS
[task 2020-01-08T20:52:50.133Z] 20:52:50 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | expected OK
[task 2020-01-08T20:52:50.134Z] 20:52:50 INFO - TEST-INFO took 40785ms
[task 2020-01-08T20:52:50.178Z] 20:52:50 INFO - PID 5308 | 1578516770161 Marionette INFO Stopped listening on port 50073
[task 2020-01-08T20:52:50.317Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2876: GetStats: Found no pipelines matching selector.
[task 2020-01-08T20:52:50.319Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2192: CloseInt: Closing PeerConnectionImpl b5edb8a3144e3229; ending call
[task 2020-01-08T20:52:50.319Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: I/jsep [1578516739768000 (id=120259084290 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-setRemoteDescription-tracks.https]: stable -> closed
[task 2020-01-08T20:52:50.320Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2863: GetStats: Found no pipelines matching selector.
[task 2020-01-08T20:52:50.321Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2192: CloseInt: Closing PeerConnectionImpl 503cc6ab06d4c664; ending call
[task 2020-01-08T20:52:50.322Z] 20:52:50 INFO - PID 5308 | [Child 13008: Main Thread]: I/jsep [1578516739772000 (id=120259084290 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-setRemoteDescription-tracks.https]: stable -> closed
[task 2020-01-08T20:52:50.626Z] 20:52:50 INFO - PID 5308 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-08T20:52:50.657Z] 20:52:50 INFO - PID 5308 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-01-08T20:52:50.804Z] 20:52:50 INFO - Browser exited with return code 0
[task 2020-01-08T20:52:50.804Z] 20:52:50 INFO - PROCESS LEAKS None
[task 2020-01-08T20:52:50.807Z] 20:52:50 INFO - PROCESS LEAKS None
[task 2020-01-08T20:52:50.810Z] 20:52:50 INFO - Closing logging queue
[task 2020-01-08T20:52:50.811Z] 20:52:50 INFO - queue closed
[task 2020-01-08T20:52:50.858Z] 20:52:50 INFO - Setting up ssl
[task 2020-01-08T20:52:50.919Z] 20:52:50 INFO - certutil |
[task 2020-01-08T20:52:50.990Z] 20:52:50 INFO - certutil |
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO - certutil |
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO - Certificate Nickname Trust Attributes
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO - SSL,S/MIME,JAR/XPI
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO -
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO - web-platform-tests CT,,
[task 2020-01-08T20:52:51.021Z] 20:52:51 INFO -
[task 2020-01-08T20:52:51.148Z] 20:52:51 INFO - Application command: C:\tasks\task_1578509226\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\testdroid.yoga-014\appdata\local\temp\tmpgvgdyo.mozrunner
[task 2020-01-08T20:52:51.159Z] 20:52:51 INFO - Starting runner
[task 2020-01-08T20:52:51.681Z] 20:52:51 INFO - PID 8272 | 1578516771672 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: networkStatus
[task 2020-01-08T20:52:52.255Z] 20:52:52 INFO - PID 8272 | 1578516772245 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-01-08T20:52:52.255Z] 20:52:52 INFO - PID 8272 | 1578516772245 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2020-01-08T20:52:52.255Z] 20:52:52 INFO - PID 8272 | 1578516772245 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-01-08T20:52:52.256Z] 20:52:52 INFO - PID 8272 | 1578516772245 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2020-01-08T20:52:55.301Z] 20:52:55 INFO - PID 8272 | 1578516775287 Marionette INFO Listening on port 50073

Comment 1

[Intermittent Failures Robot]

1 failures in 4472 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=1607924&startday=2020-01-06&endday=2020-01-12&tree=all

Comment 2

[Intermittent Failures Robot]

2 failures in 3773 pushes (0.001 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=1607924&startday=2020-01-13&endday=2020-01-19&tree=all

Comment 3

[Intermittent Failures Robot]

3 failures in 4004 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=1607924&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 2190 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=1607924&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 4

Platform breakdown:

• windows10-aarch64: 4

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

Comment 6

[Intermittent Failures Robot]

1 failures in 5233 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=1607924&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

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