windows/aarch64 - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-getStats.https.html

RESOLVED DUPLICATE of bug 1538725

Status

()

defect
P2
normal
Rank:
19
RESOLVED DUPLICATE of bug 1538725
4 months ago
4 months ago

People

(Reporter: intermittent-bug-filer, Unassigned)

Tracking

Firefox Tracking Flags

(Not tracked)

Details

#[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:58:56 INFO - TEST-FAIL | /webrtc/RTCPeerConnection-getStats.https.html | getStats() with no argument should return stats report containing peer-connection stats on an empty PC - assert_true: Expect statsReport to contain stats object of type peer-connection expected true got false
05:58:56 INFO - assert_stats_report_has_stats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:74:5
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:134:7
05:58:56 INFO - promise callback*@https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:132:6
05:58:56 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1587:25
05:58:56 INFO - promise_test/tests.promise_tests</<@https://web-platform.test:8443/resources/testharness.js:591:36
05:58:56 INFO - promise_test/tests.promise_tests<@https://web-platform.test:8443/resources/testharness.js:590:20
05:58:56 INFO - promise callback*promise_test@https://web-platform.test:8443/resources/testharness.js:589:51
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:128:3
05:58:56 INFO - TEST-FAIL | /webrtc/RTCPeerConnection-getStats.https.html | getStats() with no argument should return stats report containing peer-connection stats and outbound-track-stats - assert_equals: Expect dictionary.trackId to be string expected "string" but got "undefined"
05:58:56 INFO - assert_string_field@https://web-platform.test:8443/webrtc/dictionary-helper.js:20:3
05:58:56 INFO - validateIdField@https://web-platform.test:8443/webrtc/RTCStats-helper.js:117:3
05:58:56 INFO - validateRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:175:3
05:58:56 INFO - validateSentRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:336:3
05:58:56 INFO - validateOutboundRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:362:3
05:58:56 INFO - validateStatsReport@https://web-platform.test:8443/webrtc/RTCStats-helper.js:60:7
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:147:7
05:58:56 INFO - promise callback*@https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:146:6
05:58:56 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1587:25
05:58:56 INFO - promise_test/tests.promise_tests</<@https://web-platform.test:8443/resources/testharness.js:591:36
05:58:56 INFO - promise_test/tests.promise_tests<@https://web-platform.test:8443/resources/testharness.js:590:20
05:58:56 INFO - promise callback*promise_test@https://web-platform.test:8443/resources/testharness.js:589:51
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:138:3
05:58:56 INFO - TEST-FAIL | /webrtc/RTCPeerConnection-getStats.https.html | getStats() with no argument should return stats for no-stream tracks - assert_equals: Expect dictionary.trackId to be string expected "string" but got "undefined"
05:58:56 INFO - assert_string_field@https://web-platform.test:8443/webrtc/dictionary-helper.js:20:3
05:58:56 INFO - validateIdField@https://web-platform.test:8443/webrtc/RTCStats-helper.js:117:3
05:58:56 INFO - validateRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:175:3
05:58:56 INFO - validateSentRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:336:3
05:58:56 INFO - validateOutboundRtpStreamStats@https://web-platform.test:8443/webrtc/RTCStats-helper.js:362:3
05:58:56 INFO - validateStatsReport@https://web-platform.test:8443/webrtc/RTCStats-helper.js:60:7
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:162:7
05:58:56 INFO - promise callback*@https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:161:6
05:58:56 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1587:25
05:58:56 INFO - promise_test/tests.promise_tests</<@https://web-platform.test:8443/resources/testharness.js:591:36
05:58:56 INFO - promise_test/tests.promise_tests<@https://web-platform.test:8443/resources/testharness.js:590:20
05:58:56 INFO - promise callback*promise_test@https://web-platform.test:8443/resources/testharness.js:589:51
05:58:56 INFO - @https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html:153:3
05:58:56 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-getStats.https.html | getStats() on track associated with RtpSender should return stats report containing outbound-rtp stats - Test timed out
05:58:56 INFO - TEST-INFO | expected FAIL
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:1973: ReceivedRTPPacket RTP Processing Failed
05:58:56 INFO -
05:58:56 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCPeerConnection-getStats.https.html | getStats() on track associated with RtpReceiver should return stats report containing inbound-rtp stats - expected FAIL
05:58:56 INFO - TEST-INFO | expected FAIL
05:58:56 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-getStats.https.html | expected OK
05:58:56 INFO - TEST-INFO took 10549ms
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:1906: DeliverPacket DeliverPacket Failed, 1
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:1973: ReceivedRTPPacket RTP Processing Failed
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:1906: DeliverPacket DeliverPacket Failed, 1
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:1973: ReceivedRTPPacket RTP Processing Failed
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: D/DataChannel Deregistered 0000018862E0FC00 from the SCTP stack.
05:58:56 INFO - PID 11252 | [Child 10748: Socket Thread]: D/DataChannel Deregistered 000001886362D800 from the SCTP stack.
05:58:56 INFO - PID 11252 | (stun/INFO) STUN-CLIENT(fh/s|IP4:10.7.205.88:64930/UDP|IP4:10.7.205.88:64934/UDP(host(IP4:10.7.205.88:64930/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64934 typ host)): Received response; processing
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html):default)/CAND-PAIR(fh/s): setting pair to state SUCCEEDED: fh/s|IP4:10.7.205.88:64930/UDP|IP4:10.7.205.88:64934/UDP(host(IP4:10.7.205.88:64930/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64934 typ host)
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html):default)/STREAM(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html) transport-id=transport_0 - a85a890a:b13d08c4c02682189e206da5f905708d)/COMP(1)/CAND-PAIR(fh/s): nominated pair is fh/s|IP4:10.7.205.88:64930/UDP|IP4:10.7.205.88:64934/UDP(host(IP4:10.7.205.88:64930/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64934 typ host)
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html):default)/STREAM(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html) transport-id=transport_0 - a85a890a:b13d08c4c02682189e206da5f905708d)/COMP(1)/CAND-PAIR(fh/s): cancelling all pairs but fh/s|IP4:10.7.205.88:64930/UDP|IP4:10.7.205.88:64934/UDP(host(IP4:10.7.205.88:64930/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64934 typ host)
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html):default)/ICE-STREAM(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html) transport-id=transport_0 - a85a890a:b13d08c4c02682189e206da5f905708d): all active components have nominated candidate pairs
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html) transport-id=transport_0,1)
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html):default): all checks completed success=1 fail=0
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: D/mtransport NrIceCtx(PC:1553061532285000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html)): state 1->2
05:58:57 INFO - PID 11252 | (stun/INFO) STUN-CLIENT(I+25|IP4:10.7.205.88:64934/UDP|IP4:10.7.205.88:64930/UDP(host(IP4:10.7.205.88:64934/UDP)|candidate:0 1 UDP 2122252543 10.7.205.88 64930 typ host)): Received response; processing
05:58:57 INFO - PID 11252 | (ice/INFO) ICE-PEER(
05:58:57 INFO - PID 11252 | Timecard created 1553061532.264000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event P C:1553 | File 061532285000 (id=34359738372 url=https://we b- p|latform.test:844 3/webrtc/RTCPeerCoFnunctinecont ion - get S
05:58:57 INFO - PID 11252 | ta============ts.https.html):default)/CAND-PAIR(I+25): setting pair ====t===o =s===ta=te==== SUCCEEDED: I+25|IP4:10.7.205.88:64=9===34/===U=D===P=|IP4:10.7.205.88:============================6==49======30/UDP(=ho1553061536830 Marionette INFO Stopped listening on port 2828=st==========
05:58:57 INFO - PID 11252 | (I=P=====4:==========
05:58:57 INFO - PID 11252 | 10.7.205 .88:6493 4/ UDP)|c0an.di001date:0 1 UDP 212225250043 100 |.7.205. 8 8 0 6.493000 t1000yp | h ost)
05:58:57 INFO - PID 11252 | Constru[Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | ctor[Child 10748: Socket Thread]: D/mtransport NrIceCtx(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html)): state 1->2
05:58:57 INFO - PID 11252 | [Parent Co(92m9s6, pltGecketedu | Po_IOThreadn/INFO)eerConnect STUi]onImpl.c pWARNNpI-N:G:CL 3Ipi3E8NT( fh /s|IPpe erro4r:: 109: 1f0.7.2il | 05ePee. rCz:/buiold/nbu88:649i3ln0/UDPect|IPd4:i1onI0mp.l7.205./88:649
05:58:57 INFO - PID 11252 | s 34r c/ipc/ /cU h0Dr.P0omi02000um/src/chrom (|host(I e P4/c o:m1mon0/i.0001000 .7p.c_channel_wi| 205.88:64930/UDP)|candidate:0 1 UDPInn. iti2cal1ic22252543 1, lizing PC nCt0e.7.2 3x401
05:58:57 INFO - PID 11252 | 5.88 64934 typ host)) : Received response; processing
05:58:57 INFO - PID 11252 | (i| PeerConnectionImpl.cpp:4ce/INFO) 65ICE-PEER(PC:1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerCon ne ction-ge tStats.https.html):default)/CAND-PAIR(fh/s|): setting pair to state SUCCEEDED: fh/s| IIniP4:tiali1ze0 .7.205.88: 6
05:58:57 INFO - PID 11252 | 49 30 / 4.487000U D|P |IP4: 4.10.485000 | Dest7.205.88:64934/UDP(host(IP4:ru10.7.20cto5r.88:6493 I0/UDP)|candidnate:0 1 UDvokeP 2d | 1222PeerConn52543ectio 10.7.2n05.Impl8.cpp:8 6347 49| ~PeerConnection34 typImpl host
05:58:57 INFO - PID 11252 | )
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for a5658d81286e6ad9
05:58:57 INFO - PID 11252 | Timecard created 1553061532.220000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File [Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | | Function
05:58:57 INFO - PID 11252 | ============================[Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | ============================================================================
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | 0.0000[Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
05:58:57 INFO - PID 11252 | 00 | 0.000000 | Constructor Completed | Pee[Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
05:58:57 INFO - PID 11252 | rConne[Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
05:58:57 INFO - PID 11252 | ction[Child 10748: Socket Thread]: D/mtransport Created SRTP flow!
05:58:57 INFO - PID 11252 | Impl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
05:58:57 INFO - PID 11252 | [Child 10748: Socket Thread]: D/mtransport Created SRTP flow!
05:58:57 INFO - PID 11252 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 4.851000 | 4.850000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 92318a56614bf184
05:58:57 INFO - PID 11252 | Timecard created 1553061532.155000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.012000 | 0.012000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.013000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 4.928000 | 4.915000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for c22212f11416d5bb
05:58:57 INFO - PID 11252 | Timecard created 1553061532.103000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.015000 | 0.015000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.016000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 4.993000 | 4.977000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for e9e97d2b1b193d7e
05:58:57 INFO - PID 11252 | Timecard created 1553061532.004000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.027000 | 0.027000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.028000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 5.106000 | 5.078000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for cb474c8ed49efb3f
05:58:57 INFO - PID 11252 | Timecard created 1553061530.606000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.049000 | 0.049000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.050000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 6.530000 | 6.480000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for e1602f4fd08b408d
05:58:57 INFO - PID 11252 | Timecard created 1553061528.504000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.019000 | 0.019000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.020000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 8.645000 | 8.625000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 30c3dc49da52d1eb
05:58:57 INFO - PID 11252 | Timecard created 1553061528.439000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.021000 | 0.021000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.022000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 8.721000 | 8.699000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 0e79b97c1cfb918d
05:58:57 INFO - PID 11252 | Timecard created 1553061526.875000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 10.290000 | 10.289000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for d854dbf04d7afcba
05:58:57 INFO - PID 11252 | Timecard created 1553061526.803000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ========================================================================================================
05:58:57 INFO - PID 11252 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.000000 | 0.000000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 10.382000 | 10.382000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 101ed7995b524e7f
05:58:57 INFO - PID 11252 | Timecard created 1553061526.779000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ======================================================================================================================
05:58:57 INFO - PID 11252 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 0.047000 | 0.046000 | Set Remote Description | PeerConnectionImpl.cpp:1444 | SetRemoteDescription
05:58:57 INFO - PID 11252 | 0.061000 | 0.014000 | Create Answer | PeerConnectionImpl.cpp:1296 | CreateAnswer
05:58:57 INFO - PID 11252 | 0.061000 | 0.000000 | Set Local Description | PeerConnectionImpl.cpp:1342 | SetLocalDescription
05:58:57 INFO - PID 11252 | 0.695000 | 0.634000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2670 | IceGatheringStateChange
05:58:57 INFO - PID 11252 | 1.168000 | 0.473000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 1.295000 | 0.127000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 1.366000 | 0.071000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 1.428000 | 0.062000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 1.624000 | 0.196000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2673 | IceGatheringStateChange
05:58:57 INFO - PID 11252 | 1.890000 | 0.266000 | Ice state: checking | PeerConnectionImpl.cpp:2625 | IceConnectionStateChange
05:58:57 INFO - PID 11252 | 5.016000 | 3.126000 | Ice state: connected | PeerConnectionImpl.cpp:2628 | IceConnectionStateChange
05:58:57 INFO - PID 11252 | 10.426000 | 5.410000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 069e16a5ded38d50
05:58:57 INFO - PID 11252 | Timecard created 1553061526.768000
05:58:57 INFO - PID 11252 | Timestamp | Delta | Event | File | Function
05:58:57 INFO - PID 11252 | ======================================================================================================================
05:58:57 INFO - PID 11252 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:338 | PeerConnectionImpl
05:58:57 INFO - PID 11252 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:465 | Initialize
05:58:57 INFO - PID 11252 | 0.049000 | 0.048000 | Create Offer | PeerConnectionImpl.cpp:1256 | CreateOffer
05:58:57 INFO - PID 11252 | 0.051000 | 0.002000 | Set Local Description | PeerConnectionImpl.cpp:1342 | SetLocalDescription
05:58:57 INFO - PID 11252 | 0.087000 | 0.036000 | Set Remote Description | PeerConnectionImpl.cpp:1444 | SetRemoteDescription
05:58:57 INFO - PID 11252 | 0.119000 | 0.032000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2670 | IceGatheringStateChange
05:58:57 INFO - PID 11252 | 1.488000 | 1.369000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2673 | IceGatheringStateChange
05:58:57 INFO - PID 11252 | 1.526000 | 0.038000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 1.576000 | 0.050000 | Add Ice Candidate | PeerConnectionImpl.cpp:1614 | AddIceCandidate
05:58:57 INFO - PID 11252 | 2.410000 | 0.834000 | Ice state: checking | PeerConnectionImpl.cpp:2625 | IceConnectionStateChange
05:58:57 INFO - PID 11252 | 5.175000 | 2.765000 | Ice state: connected | PeerConnectionImpl.cpp:2628 | IceConnectionStateChange
05:58:57 INFO - PID 11252 | 10.502000 | 5.327000 | Destructor Invoked | PeerConnectionImpl.cpp:347 | ~PeerConnectionImpl
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:372: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 3cc6846a6acc0ee2
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: D/DataChannel 0000018863014D40: Close()ing 000001885F0FE1C0
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl 3c20811c79f43eff; ending call
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/jsep [1553061532283000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html)]: stable -> closed
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2313: CloseInt: Closing PeerConnectionImpl f5edfce6812f35b1; ending call
05:58:57 INFO - PID 11252 | [Child 10748: Main Thread]: I/jsep [1553061532285000 (id=34359738372 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-getStats.https.html)]: stable -> closed
05:58:57 INFO - PID 11252 | JavaScript error: , line 0: InvalidStateError: An[Parent 9296, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | attempt was made to use an object that is not, or is no longer, usable
05:58:57 INFO - PID 11252 | [Child 10748: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:570: SendAudioFrame Engine not transmitting
05:58:57 INFO - PID 11252 | [Child 10748: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:570: SendAudioFrame Engine not transmitting
05:58:57 INFO - PID 11252 | [Child 2788, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | [Child 2788, Chrome_Ch[Child 10748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | [Child 10748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | [Parent 9296, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | [Child 10396, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
05:58:57 INFO - PID 11252 | [Child 10396, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win[Parent 9296, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341

Keywords: regression

Exactly the same thing as the others; aarch64 is too slow to run these tests in less than 10 seconds.

Is there some way we could increase the default timeout (10s) on aarch64 on wpt? Because I think we need to.

Rank: 19
Component: WebRTC → WebRTC: Signaling
Flags: needinfo?(james)
Priority: P5 → P2

Yes, we need to set a timeout-multiplier for this configuration. That can be set in the task config, or can be set in the code (we have tended to do the latter); see https://searchfox.org/mozilla-central/source/testing/web-platform/tests/tools/wptrunner/wptrunner/browsers/firefox.py#46

Flags: needinfo?(james)

Just adding Joel because afaik he is in charge of the aarch64 testing and so is the right person to make the call about what the value should be here (and probably also to make the change since I think it's straightforward).

Flags: needinfo?(jmaher)
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → DUPLICATE
Duplicate of bug: 1538725

thanks for bringing this up- there are some features which are not [fully] implemented on windows/aarch64 and other tests that just fail. We do find that the tests run about 2x slower than what we see on windows normally (part of this is the lower end hardware).

Lets move the discussion to bug 1538725.

Flags: needinfo?(jmaher)
You need to log in before you can comment on or make changes to this bug.