Closed Bug 1536922 Opened 5 years ago Closed 5 years ago

windows/aarch64 - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-remote-track-mute.https.html | multiple issues

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1538725

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: regression)

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

https://treeherder.mozilla.org/logviewer.html#?job_id=234924560&repo=try

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

Test: wpt10

Platform: windows10-aarch64

Context:
05:59:23 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-remote-track-mute.https.html | Changing transceiver direction to 'inactive' mutes the remote track - Test timed out
05:59:23 INFO -
05:59:23 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-remote-track-mute.https.html | Changing transceiver direction to 'sendrecv' unmutes the remote track - expected PASS
05:59:23 INFO -
05:59:23 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-remote-track-mute.https.html | pc.close() mutes remote tracks - expected TIMEOUT
05:59:23 INFO - TEST-INFO | expected TIMEOUT
05:59:23 INFO - TEST-TIMEOUT | /webrtc/RTCPeerConnection-remote-track-mute.https.html | took 10410ms
05:59:23 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/STREAM(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 80ee7bc3:4ba6695bea661577aeff21016a278755)/COMP(1)/CAND-PAIR(dprJ): nominated pair is dprJ|IP4:10.7.205.88:64993/UDP|IP4:10.7.205.88:64989/UDP(host(IP4:10.7.205.88:64993/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64989 typ host)
05:59:23 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/STREAM(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 80ee7bc3:4ba6695bea661577aeff21016a278755)/COMP(1)/CAND-PAIR(dprJ): cancelling all pairs but dprJ|IP4:10.7.205.88:64993/UDP|IP4:10.7.205.88:64989/UDP(host(IP4:10.7.205.88:64993/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64989 typ host)
05:59:23 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/ICE-STREAM(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 80ee7bc3:4ba6695bea661577aeff21016a278755): all active components have nominated candidate pairs
05:59:23 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0,1)
05:59:23 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
05:59:23 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default): all checks completed success=1 fail=0
05:59:23 INFO - PID 9832 | (stun/INFO) STUN-CLIENT(XJhw|IP4:10.7.205.88:64989/UDP|IP4:10.7.205.88:64993/UDP(host(IP4:10.7.205.88:64989/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64993 typ host)): Received response; processing
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/CAND-PAIR(XJhw): setting pair to state SUCCEEDED: XJhw|IP4:10.7.205.88:64989/UDP|IP4:10.7.205.88:64993/UDP(host(IP4:10.7.205.88:64989/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64993 typ host)
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/STREAM(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 9896c015:298e71dff6a24c2fcc07b25144abe08f)/COMP(1)/CAND-PAIR(XJhw): nominated pair is XJhw|IP4:10.7.205.88:64989/UDP|IP4:10.7.205.88:64993/UDP(host(IP4:10.7.205.88:64989/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64993 typ host)
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/STREAM(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 9896c015:298e71dff6a24c2fcc07b25144abe08f)/COMP(1)/CAND-PAIR(XJhw): cancelling all pairs but XJhw|IP4:10.7.205.88:64989/UDP|IP4:10.7.205.88:64993/UDP(host(IP4:10.7.205.88:64989/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64993 typ host)
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/ICE-STREAM(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0 - 9896c015:298e71dff6a24c2fcc07b25144abe08f): all active components have nominated candidate pairs
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0,1)
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default): all checks completed success=1 fail=0
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:59:24 INFO - PID 9832 | 1553061563745 Marionette INFO Stopped listening on port 2828
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport NrIceCtx(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html)): state 1->2
05:59:24 INFO - PID 9832 | (stun/INFO) STUN-CLIENT(dprJ|IP4:10.7.205.88:64993/UDP|IP4:10.7.205.88:64989/UDP(host(IP4:10.7.205.88:64993/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64989 typ host)): Received response; processing
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/CAND-PAIR(dprJ): setting pair to state SUCCEEDED: dprJ|IP4:10.7.205.88:64993/UDP|IP4:10.7.205.88:64989/UDP(host(IP4:10.7.205.88:64993/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64989 typ host)
05:59:24 INFO - PID 9832 | [Child 8172: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl 103a04900e253703; ending call
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:59:24 INFO - PID 9832 | [Child 8172: Socket Thread]: D/mtransport NrIceCtx(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html)): state 1->2
05:59:24 INFO - PID 9832 | [Child 8172: Main Thread]: I/jsep [1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html)]: stable -> closed
05:59:24 INFO - PID 9832 | [(stuP[Child 8172: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl cec8c7c462ea5889; ending call
05:59:24 INFO - PID 9832 | na/rent 4452, GIecko_IOThread] WANFOR) STUN-CLIENT(X[Child 8172: Main Thread]: I/jsep [1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html)]: stable -> closed
05:59:24 INFO - PID 9832 | Jhw|IP4:10.7.205.88:64989/UDP|IP4:10.7.205.88N:I649NG: p93/UDiP(host(IP4:1[Child 8172: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2189: CheckApiState: called API while closed
05:59:24 INFO - PID 9832 | 0.pe err7.o2r05.88:: 64989/UDP109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341)|ca
05:59:24 INFO - PID 9832 | ndidate:0 1 UDP 2122252543 10.7.205.88 64993 typ host)): Received response; processing
05:59:24 INFO - PID 9832 | JavaScript error: , line 0: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
05:59:24 INFO - PID 9832 | (ice/INFO) ICE-PEER(PC:1553061558797000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html):default)/CAND-PAIR(XJhw): setting pair to state SUCCEEDED: XJhw|IP4:10.7.205.88[Ch:64989/UildDP|IP4:10.7.20 312,5.8 Ch8:64993/UDrP(host(IP4:10o.7.m205.e_ChildThr8ead] WARNIN8G: pipe error: 109::64 [989f/UCDiP)lhi|lcde az ndi1126:0, Cdh/roate:0mb e1_Chuil dTil
05:59:24 INFO - PID 9832 | ThUdiDP/mrb 21ueea2dcar]i WARNlId/s2d2rcN G/i: 5c25p4irpe3 pceea ter1/chromiumr0o/rs:r d.10c9: file z /7:/1cb5hui5ld/br[3.20Paorum615ene0/tico5ml 445md53on/ip.c_ch2.,6 9a/G88nnselrc_ 62409w00/
05:59:24 INFO - PID 9832 | i9in3. pcc,ecko lin_t e 341
05:59:24 INFO - PID 9832 | c/TIOThread] WARNINiG: ypcphr omesihmopsie utterr)ormamp : 1 |
05:59:24 INFO - PID 9832 | 09: fi [/lDs[Child 8172: Socket Thread]: E/mtransport Couldn't send media on 'PC:1553061558810000 (id=23622320132 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-remote-track-mute.https.html) transport-id=transport_0'
05:59:24 INFO - PID 9832 | elrceCta z:/bh u ild/ibuil d/l d/ s[Child 8172: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:59:24 INFO - PID 9832 | 8crc/ipch/17rome/c[Child 8172: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:1002: GetIceStats: Error getting candidate pairs for transport id "transport_0"
05:59:24 INFO - PID 9832 | | Ec2,hr[Child 8172: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:1002: GetIceStats: Error getting candidate pairs for transport id "transport_0"
05:59:24 INFO - PID 9832 | omium/src Chro/chrome/common/ipc_chaonnel_win.cc, line 341
05:59:24 INFO - PID 9832 | me_ChildThread] WARm[Chmvion/ld 5300, Cipcenthrome_ _ChildThrNeaINch d] WARannNI NG: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, le Gine l _w341 in.c
05:59:24 INFO - PID 9832 | c: [Parent 4[C4h52, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/s,rc/ipc /l ch romium/si ne 341
05:59:24 INFO - PID 9832 | r pc|ip/chr ome/coFmmion/ipcl_e[ Cc han n el _win.cec, l ine 341
05:59:24 INFO - PID 9832 | error : | [C1hild 1009F456, Chrome_Ch: ufildThreand] WAcRNtINioniG: l epipe er r o rz: :/build/ 10 9: bu ifile z:/bu i
05:59:24 INFO - PID 9832 | lld/bd/src/=iuil=p==d=c//=srcc/=ihrpo=mc/iuchromium/src/chrome/common/ipc_channel_win.cc, line 341
05:59:24 INFO - PID 9832 | =====================================m/s================r====c/chrom===e/co=mm==========on/ipc_ch====================a===n=n=======el_w====in.cc, line====
05:59:24 INFO - PID 9832 | 341
05:59:24 INFO - PID 9832 | 0.000000 | [ C 0.00000h0 il| Constrd 81uctor Completed | PeerConnectionImpl.cpp:338 | PeerConnec7t2, Chrome_ChiildonImpl T
05:59:24 INFO - PID 9832 | h 0.00100read0] WARNI NG:| p ipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome 0.001000 | Initializing PC Ctx / | PeerConnectionImpl.cpp:465 | Initialize common/ipc_channel_win.cc, line 3 41
05:59:24 INFO - PID 9832 | 0.016000 | 0.015000 | Set Remote Description | PeerConnectionImpl.cpp:1444 | SetRemoteDescription
05:59:24 INFO - PID 9832 | 0.028000 | 0.012000 | Create Answer | PeerConnectionImpl.cpp:1296 | CreateAnswer
05:59:24 INFO - PID 9832 | 0.028000 | 0.000000 | Set Local Description | PeerConnectionImpl.cpp:1342 | SetLocalDescription
05:59:24 INFO - PID 9832 | 0.072000 | 0.044000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2670 | IceGatheringStateChange
05:59:24 INFO - PID 9832 | 0.277000 | 0.205000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 0.428000 | 0.151000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 0.510000 | 0.082000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 0.584000 | 0.074000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 0.812000 | 0.228000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2673 | IceGatheringStateChange
05:59:24 INFO - PID 9832 | 1.120000 | 0.308000 | Ice state: checking | PeerConnectionImpl.cpp:2625 | IceConnectionStateChange
05:59:24 INFO - PID 9832 | 4.649000 | 3.529000 | Ice state: connected | PeerConnectionImpl.cpp:2628 | IceConnectionStateChange
05:59:24 INFO - PID 9832 | 10.383000 | 5.734000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:59:24 INFO - PID 9832 | [Child 8172: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 3fa6e88bcfc96637
05:59:24 INFO - PID 9832 | Timecard created 1553061553.666000
05:59:24 INFO - PID 9832 | Timestamp | Delta | Event | File | Function
05:59:24 INFO - PID 9832 | ======================================================================================================================
05:59:24 INFO - PID 9832 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:59:24 INFO - PID 9832 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:59:24 INFO - PID 9832 | 0.034000 | 0.033000 | Create Offer | PeerConnectionImpl.cpp:1256 | CreateOffer
05:59:24 INFO - PID 9832 | 0.035000 | 0.001000 | Set Local Description | PeerConnectionImpl.cpp:1342 | SetLocalDescription
05:59:24 INFO - PID 9832 | 0.057000 | 0.022000 | Set Remote Description | PeerConnectionImpl.cpp:1444 | SetRemoteDescription
05:59:24 INFO - PID 9832 | 0.078000 | 0.021000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2670 | IceGatheringStateChange
05:59:24 INFO - PID 9832 | 0.668000 | 0.590000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2673 | IceGatheringStateChange
05:59:24 INFO - PID 9832 | 0.714000 | 0.046000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 0.773000 | 0.059000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:59:24 INFO - PID 9832 | 1.841000 | 1.068000 | Ice state: checking | PeerConnectionImpl.cpp:2625 | IceConnectionStateChange
05:59:24 INFO - PID 9832 | 4.829000 | 2.988000 | Ice state: connected | PeerConnectionImpl.cpp:2628 | IceConnectionStateChange
05:59:24 INFO - PID 9832 | 10.497000 | 5.668000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:59:24 INFO - PID 9832 | [Child 8172: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for c74790f5abc5d98d

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