Closed Bug 1606109 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | Executor hit external timeout (this may indicate a hang)

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2019-12-27T12:47:25.465Z] 12:47:25 INFO - TEST-START | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html
[task 2019-12-27T12:47:25.465Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport NrIceCtx(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): trickling candidate candidate:1 1 TCP 2105524479 082e5d6f-289d-4289-babb-94eaf04bd079.local 9 typ host tcptype active
[task 2019-12-27T12:47:25.466Z] 12:47:25 INFO - Clearing pref media.navigator.permission.disabled
[task 2019-12-27T12:47:25.467Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html) transport-id=transport_0', no candidates.
[task 2019-12-27T12:47:25.467Z] 12:47:25 INFO - PID 26536 | (ice/ERR) ICE(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): peer (PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:56201/TCP) active
[task 2019-12-27T12:47:25.467Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport NrIceCtx(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): trickling candidate candidate:0 2 UDP 2122252542 082e5d6f-289d-4289-babb-94eaf04bd079.local 45261 typ host
[task 2019-12-27T12:47:25.469Z] 12:47:25 INFO - PID 26536 | (ice/ERR) ICE(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): peer (PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:45261/UDP)
[task 2019-12-27T12:47:25.469Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport NrIceCtx(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): trickling candidate candidate:1 2 TCP 2105524478 082e5d6f-289d-4289-babb-94eaf04bd079.local 9 typ host tcptype active
[task 2019-12-27T12:47:25.470Z] 12:47:25 INFO - PID 26536 | (ice/ERR) ICE(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): peer (PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:64687/TCP) active
[task 2019-12-27T12:47:25.470Z] 12:47:25 INFO - PID 26536 | (ice/INFO) ICE(PC:1577450845287156 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): All candidates initialized
[task 2019-12-27T12:47:25.472Z] 12:47:25 INFO - Clearing pref privacy.resistFingerprinting.reduceTimerPrecision.jitter
[task 2019-12-27T12:47:25.472Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport NrIceCtx(PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): trickling candidate candidate:0 1 UDP 2122252543 71a21c9c-b5b7-494f-bc13-65b8a3996006.local 50076 typ host
[task 2019-12-27T12:47:25.472Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html) transport-id=transport_0', no candidates.
[task 2019-12-27T12:47:25.474Z] 12:47:25 INFO - PID 26536 | (ice/ERR) ICE(PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): peer (PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:50076/UDP)
[task 2019-12-27T12:47:25.474Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport NrIceCtx(PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): trickling candidate candidate:1 1 TCP 2105524479 71a21c9c-b5b7-494f-bc13-65b8a3996006.local 9 typ host tcptype active
[task 2019-12-27T12:47:25.474Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html) transport-id=transport_0', no candidates.
[task 2019-12-27T12:47:25.476Z] 12:47:25 INFO - Clearing pref media.navigator.streams.fake
[task 2019-12-27T12:47:25.476Z] 12:47:25 INFO - PID 26536 | (ice/ERR) ICE(PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): peer (PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:62871/TCP) active
[task 2019-12-27T12:47:25.476Z] 12:47:25 INFO - PID 26536 | [Child 29912: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html) transport-id=transport_0', no candidates.
[task 2019-12-27T12:47:25.476Z] 12:47:25 INFO - PID 26536 | (ice/INFO) ICE(PC:1577450845287782 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)): All candidates initialized
[task 2019-12-27T12:47:25.478Z] 12:47:25 INFO - PID 26536 | [Child 29912: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2192: CloseInt: Closing PeerConnectionImpl 85fecf7685cba621; ending call
[task 2019-12-27T12:47:25.478Z] 12:47:25 INFO - PID 26536 | [Child 29912: Main Thread]: I/jsep [1577450845304561 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)]: have-remote-offer -> closed
[task 2019-12-27T12:47:25.478Z] 12:47:25 INFO - PID 26536 | [Child 29912: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2192: CloseInt: Closing PeerConnectionImpl 70cc9775949ea629; ending call
[task 2019-12-27T12:47:25.480Z] 12:47:25 INFO - PID 26536 | [Child 29912: Main Thread]: I/jsep [1577450845305065 (id=98784247824 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-setRemoteDescription-rollback.html)]: stable -> closed
[task 2019-12-27T12:47:25.480Z] 12:47:25 INFO - Clearing pref privacy.reduceTimerPrecision
[task 2019-12-27T12:47:25.480Z] 12:47:25 INFO - Setting pref media.navigator.permission.disabled (true)
[task 2019-12-27T12:47:25.481Z] 12:47:25 INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter (false)
[task 2019-12-27T12:47:25.481Z] 12:47:25 INFO - Setting pref media.navigator.streams.fake (true)
[task 2019-12-27T12:47:25.482Z] 12:47:25 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2019-12-27T12:47:25.483Z] 12:47:25 INFO - Closing window 125
[task 2019-12-27T12:47:25.498Z] 12:47:25 INFO - PID 26536 | [2019-12-27T12:47:25Z ERROR audioipc_server::server] Unexpected message Err(Custom { kind: BrokenPipe, error: "broken pipe" }) during data_callback
[task 2019-12-27T12:47:45.501Z] 12:47:45 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-tracks.https.html | Executor hit external timeout (this may indicate a hang)
[task 2019-12-27T12:47:45.501Z] 12:47:45 INFO - File "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
[task 2019-12-27T12:47:45.501Z] 12:47:45 INFO - self.__bootstrap_inner()
[task 2019-12-27T12:47:45.501Z] 12:47:45 INFO - File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - self.run()
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - File "/usr/lib/python2.7/threading.py", line 1071, in run
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - self.finished.wait(self.interval)
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - File "/usr/lib/python2.7/threading.py", line 614, in wait
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - self.__cond.wait(timeout)
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - File "/usr/lib/python2.7/threading.py", line 359, in wait
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - _sleep(delay)
[task 2019-12-27T12:47:45.502Z] 12:47:45 INFO - TEST-INFO took 20118ms

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.