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

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-01-01T10:49:32.862Z] 10:49:32 INFO - TEST-START | /webrtc/RTCPeerConnection-setRemoteDescription-replaceTrack.https.html
[task 2020-01-01T10:49:32.862Z] 10:49:32 INFO - Clearing pref media.navigator.permission.disabled
[task 2020-01-01T10:49:32.864Z] 10:49:32 INFO - Clearing pref privacy.resistFingerprinting.reduceTimerPrecision.jitter
[task 2020-01-01T10:49:32.867Z] 10:49:32 INFO - Clearing pref media.navigator.streams.fake
[task 2020-01-01T10:49:32.875Z] 10:49:32 INFO - Clearing pref privacy.reduceTimerPrecision
[task 2020-01-01T10:49:32.882Z] 10:49:32 INFO - Setting pref media.navigator.permission.disabled (true)
[task 2020-01-01T10:49:32.898Z] 10:49:32 INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter (false)
[task 2020-01-01T10:49:32.899Z] 10:49:32 INFO - Setting pref media.navigator.streams.fake (true)
[task 2020-01-01T10:49:32.915Z] 10:49:32 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-01-01T10:49:32.917Z] 10:49:32 INFO - Closing window 42949672974
[task 2020-01-01T10:49:32.973Z] 10:49:32 INFO - PID 14886 | JavaScript error: chrome://marionette/content/listener.js, line 378: SecurityError: Permission denied to access property "windowUtils" on cross-origin object
[task 2020-01-01T10:49:33.460Z] 10:49:33 INFO - PID 14886 | [2020-01-01T10:49:33Z ERROR audioipc_server::server] Unexpected message Err(Custom { kind: BrokenPipe, error: "broken pipe" }) during data_callback
[task 2020-01-01T10:49:33.496Z] 10:49:33 INFO - PID 14886 | [2020-01-01T10:49:33Z ERROR audioipc_server::server] Unexpected message Err(Custom { kind: BrokenPipe, error: "broken pipe" }) during data_callback
[task 2020-01-01T10:49:33.674Z] 10:49:33 INFO - PID 14886 | [Child 17660: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:329: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2020-01-01T10:49:33.674Z] 10:49:33 INFO - PID 14886 | (unknown/INFO) insert '' (registry) succeeded:
[task 2020-01-01T10:49:33.675Z] 10:49:33 INFO - PID 14886 | (registry/INFO) Initialized registry
[task 2020-01-01T10:49:33.675Z] 10:49:33 INFO - PID 14886 | [Parent 14886: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2020-01-01T10:49:33.679Z] 10:49:33 INFO - PID 14886 | [Child 17660: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:329: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2020-01-01T10:49:33.679Z] 10:49:33 INFO - PID 14886 | [Parent 14886: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2020-01-01T10:49:33.679Z] 10:49:33 INFO - PID 14886 | [Child 17660: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:329: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2020-01-01T10:49:33.680Z] 10:49:33 INFO - PID 14886 | [Parent 14886: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2020-01-01T10:49:33.681Z] 10:49:33 INFO - PID 14886 | [Child 17660: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:329: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2020-01-01T10:49:33.681Z] 10:49:33 INFO - PID 14886 | [Parent 14886: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-setRemoteDescription-replaceTrack.https.html | Executor hit external timeout (this may indicate a hang)
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - File "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - self.__bootstrap_inner()
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - self.run()
[task 2020-01-01T10:49:53.553Z] 10:49:53 INFO - File "/usr/lib/python2.7/threading.py", line 1071, in run
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - self.finished.wait(self.interval)
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - File "/usr/lib/python2.7/threading.py", line 614, in wait
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - self.__cond.wait(timeout)
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - File "/usr/lib/python2.7/threading.py", line 359, in wait
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - _sleep(delay)
[task 2020-01-01T10:49:53.554Z] 10:49:53 INFO - TEST-INFO took 20699ms
[task 2020-01-01T10:51:04.599Z] 10:51:04 INFO - Browser exited with return code -15
[task 2020-01-01T10:51:04.599Z] 10:51:04 INFO - PROCESS LEAKS None
[task 2020-01-01T10:51:04.599Z] 10:51:04 INFO - PROCESS LEAKS None
[task 2020-01-01T10:51:04.600Z] 10:51:04 INFO - IOError on command, setting status to CRASH
[task 2020-01-01T10:51:04.600Z] 10:51:04 INFO - Closing logging queue
[task 2020-01-01T10:51:04.600Z] 10:51:04 INFO - queue closed
[task 2020-01-01T10:51:04.621Z] 10:51:04 INFO - Setting up ssl
[task 2020-01-01T10:51:04.642Z] 10:51:04 INFO - certutil |
[task 2020-01-01T10:51:04.670Z] 10:51:04 INFO - certutil |
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO - certutil |
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO - Certificate Nickname Trust Attributes
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO - SSL,S/MIME,JAR/XPI
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO -
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO - web-platform-tests CT,,
[task 2020-01-01T10:51:04.686Z] 10:51:04 INFO -
[task 2020-01-01T10:51:04.702Z] 10:51:04 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpIVl23s.mozrunner
[task 2020-01-01T10:51:04.710Z] 10:51:04 INFO - Starting runner
[task 2020-01-01T10:51:05.117Z] 10:51:05 INFO - PID 17705 | 1577875865111 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: networkStatus
[task 2020-01-01T10:51:05.657Z] 10:51:05 INFO - PID 17705 | 1577875865645 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-01-01T10:51:05.657Z] 10:51:05 INFO - PID 17705 | 1577875865645 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2020-01-01T10:51:05.657Z] 10:51:05 INFO - PID 17705 | 1577875865645 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-01-01T10:51:05.658Z] 10:51:05 INFO - PID 17705 | 1577875865646 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2020-01-01T10:51:12.043Z] 10:51:12 INFO - PID 17705 | 1577875872037 Marionette INFO Listening on port 50365
[task 2020-01-01T10:51:12.145Z] 10:51:12 INFO - PID 17705 | JavaScript error: chrome://marionette/content/listener.js, line 378: SecurityError: Permission denied to access property "windowUtils" on cross-origin object

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-qr: 1

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

Comment 2

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

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