Closed Bug 1523628 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/simplecall.https.html | Can set up a basic WebRTC call. - Test timed out

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)

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

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

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

[task 2019-01-29T13:23:58.382Z] 13:23:58 INFO - Closing window 8589934593
[task 2019-01-29T13:23:58.418Z] 13:23:58 INFO - PID 17698 | [Child 17925: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl 8204fab2ef58ded7; ending call
[task 2019-01-29T13:23:58.420Z] 13:23:58 INFO - PID 17698 | [Child 17925: Main Thread]: I/jsep [1548768228378469 (id=8589934596 url=https://web-platform.test:8443/webrtc/simplecall.https.html)]: stable -> closed
[task 2019-01-29T13:23:58.426Z] 13:23:58 INFO - PID 17698 | [Child 17925: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl dd8c8df74517e5e6; ending call
[task 2019-01-29T13:23:58.427Z] 13:23:58 INFO - PID 17698 | [Child 17925: Main Thread]: I/jsep [1548768228398978 (id=8589934596 url=https://web-platform.test:8443/webrtc/simplecall.https.html)]: stable -> closed
[task 2019-01-29T13:23:58.428Z] 13:23:58 INFO - PID 17698 | JavaScript error: , line 0: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
[task 2019-01-29T13:23:58.431Z] 13:23:58 INFO -
[task 2019-01-29T13:23:58.432Z] 13:23:58 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/simplecall.https.html | Can set up a basic WebRTC call. - Test timed out
[task 2019-01-29T13:23:58.434Z] 13:23:58 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/simplecall.https.html | expected OK
[task 2019-01-29T13:23:58.435Z] 13:23:58 INFO - TEST-INFO took 10483ms
[task 2019-01-29T13:23:58.458Z] 13:23:58 INFO - PID 17698 | 1548768238451 Marionette INFO Stopped listening on port 2828
[task 2019-01-29T13:25:02.544Z] 13:25:02 INFO - PID 17698 | ExceptionHandler::GenerateDump cloned child 18077
[task 2019-01-29T13:25:02.545Z] 13:25:02 INFO - PID 17698 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2019-01-29T13:25:02.545Z] 13:25:02 INFO - PID 17698 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2019-01-29T13:25:02.689Z] 13:25:02 INFO - PID 17698 | Exiting due to channel error.
[task 2019-01-29T13:25:02.692Z] 13:25:02 INFO - PID 17698 | Exiting due to channel error.
[task 2019-01-29T13:25:02.693Z] 13:25:02 INFO - PID 17698 | Exiting due to channel error.
[task 2019-01-29T13:25:02.694Z] 13:25:02 INFO - PID 17698 | Exiting due to channel error.
[task 2019-01-29T13:25:02.756Z] 13:25:02 INFO - Browser exited with return code 11
[task 2019-01-29T13:25:02.757Z] 13:25:02 INFO - PROCESS LEAKS None
[task 2019-01-29T13:25:02.759Z] 13:25:02 INFO - Closing logging queue
[task 2019-01-29T13:25:02.759Z] 13:25:02 INFO - queue closed
[task 2019-01-29T13:25:02.788Z] 13:25:02 INFO - Setting up ssl
[task 2019-01-29T13:25:02.824Z] 13:25:02 INFO - certutil |
[task 2019-01-29T13:25:02.865Z] 13:25:02 INFO - certutil |
[task 2019-01-29T13:25:02.886Z] 13:25:02 INFO - certutil |
[task 2019-01-29T13:25:02.886Z] 13:25:02 INFO - Certificate Nickname Trust Attributes
[task 2019-01-29T13:25:02.886Z] 13:25:02 INFO - SSL,S/MIME,JAR/XPI
[task 2019-01-29T13:25:02.886Z] 13:25:02 INFO -
[task 2019-01-29T13:25:02.887Z] 13:25:02 INFO - web-platform-tests CT,,

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

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

14:54:58 INFO - TEST-START | /webrtc/simplecall.https.html

14:55:18 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/sSharedThreadPool in xpcom-shutdown-threads. Waiting for pools "WebRTCPD" "CubebOperation" "MediaTimer"
14:55:18 INFO - PID 6712 | rc/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:18 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:18 INFO - PID 6712 | (stun/INFO) STUN-CLIENT(consent): Received response; processing
14:55:18 INFO - PID 6712 | (ice/INFO) ICE(PC:1554303298763000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html))/STREAM(PC:1554303298763000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html) transport-id=transport_0 - 95f4fe5f:f7e98e3259f3c8669592c00e1f99d7fa)/COMP(1): Consent refreshed
14:55:19 INFO - PID 6712 | ###!!! [Child][MessageChannel] Error: (msgtype=0x3500FE,name=PContent::Msg_AccumulateChildHistograms) Closed channel: cannot send/recv
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: MsgDropped in ContentChild: file z:/build/build/src/dom/ipc/ContentChild.cpp, line 2290
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: '!ipcActor->SendAccumulateChildHistograms(histogramsToSend)', file z:/build/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 294
14:55:19 INFO - PID 6712 | ###!!! [Child][MessageChannel] Error: (msgtype=0x3500FF,name=PContent::Msg_AccumulateChildKeyedHistograms) Closed channel: cannot send/recv
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: MsgDropped in ContentChild: file z:/build/build/src/dom/ipc/ContentChild.cpp, line 2290
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file z:/build/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 298
14:55:19 INFO - PID 6712 | ###!!! [Child][MessageChannel] Error: (msgtype=0x350103,name=PContent::Msg_RecordDiscardedData) Closed channel: cannot send/recv
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: MsgDropped in ContentChild: file z:/build/build/src/dom/ipc/ContentChild.cpp, line 2290
14:55:19 INFO - PID 6712 | [Child 7092, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file z:/build/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 313
14:55:20 INFO - PID 6712 | (stun/INFO) STUN-CLIENT(consent): Received response; processing
14:55:20 INFO - PID 6712 | (ice/INFO) ICE(PC:1554303298731000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html))/STREAM(PC:1554303298731000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html) transport-id=transport_0 - 5b03f0d4:9166ddfd3597d2924e3bc06fc2d3f817)/COMP(1): Consent refreshed
14:55:23 INFO - PID 6712 | --DOMWINDOW == 2 (0000020BD943EC00) [pid = 2964] [serial = 4] [outer = 0000000000000000] [url = about:blank]
14:55:24 INFO - PID 6712 | (stun/INFO) STUN-CLIENT(consent): Received response; processing
14:55:24 INFO - PID 6712 | (ice/INFO) ICE(PC:1554303298763000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html))/STREAM(PC:1554303298763000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html) transport-id=transport_0 - 95f4fe5f:f7e98e3259f3c8669592c00e1f99d7fa)/COMP(1): Consent refreshed
14:55:26 INFO - PID 6712 | (stun/INFO) STUN-CLIENT(consent): Received response; processing
14:55:26 INFO - PID 6712 | (ice/INFO) ICE(PC:1554303298731000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html))/STREAM(PC:1554303298731000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html) transport-id=transport_0 - 5b03f0d4:9166ddfd3597d2924e3bc06fc2d3f817)/COMP(1): Consent refreshed
14:55:28 INFO -
14:55:28 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/simplecall.https.html | Can set up a basic WebRTC call. - Test timed out
14:55:28 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/simplecall.https.html | expected OK
14:55:28 INFO - TEST-INFO took 30483ms
14:55:28 INFO - PID 6712 | 1554303328787 Marionette INFO Stopped listening on port 2828
14:55:28 INFO - PID 6712 | [Child 1740: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2271: CloseInt: Closing PeerConnectionImpl c1abe819b9473243; ending call
14:55:28 INFO - PID 6712 | [Child 1740: Main Thread]: I/jsep [1554303298731000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html)]: stable -> closed
14:55:28 INFO - PID 6712 | [Child 1740: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2271: CloseInt: Closing PeerConnectionImpl 297eb3e1a85b3b90; ending call
14:55:28 INFO - PID 6712 | [Child 1740: Main Thread]: I/jsep [1554303298763000 (id=10737418244 url=https://web-platform.test:8443/webrtc/simplecall.https.html)]: stable -> closed
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Child 3948, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Child 3948, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [GPU 8360, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [GPU 8360, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
14:55:28 INFO - PID 6712 | [GPU 8360, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [GPU 8360, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Parent 2588, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Child 2964, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
14:55:28 INFO - PID 6712 | [Child 2964, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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