Closed
Bug 1779489
Opened 3 years ago
Closed 2 years ago
Intermittent /webrtc/protocol/rtp-extension-support.html | assertion count 2 is more than expected 0 assertions
Categories
(Core :: WebRTC, defect, P5)
Core
WebRTC
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: assertion, intermittent-failure)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=384269028&repo=mozilla-esr102
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LO3fUbyoTCG__5btP8m1WA/runs/0/artifacts/public/logs/live_backing.log
[task 2022-07-13T19:59:08.374Z] 19:59:08 INFO - TEST-START | /webrtc/protocol/rtp-extension-support.html
[task 2022-07-13T19:59:08.379Z] 19:59:08 INFO - Closing window fd425dfb-3510-4612-8d1c-d2a0d695a86b
[task 2022-07-13T19:59:15.995Z] 19:59:15 INFO - PID 3972 | [Child 8748, Main Thread] ###!!! ASSERTION: No stream: 'Error', file /builds/worker/checkouts/gecko/dom/html/HTMLCanvasElement.cpp:807
[task 2022-07-13T19:59:16.008Z] 19:59:16 INFO - PID 3972 | [Child 8748, Main Thread] ###!!! ASSERTION: No stream: 'Error', file /builds/worker/checkouts/gecko/dom/html/HTMLCanvasElement.cpp:807
[task 2022-07-13T19:59:16.009Z] 19:59:16 INFO - PID 3972 | Timecard created 1657742347.998000
[task 2022-07-13T19:59:16.009Z] 19:59:16 INFO - PID 3972 | Timestamp | Delta | Event | File | Function
[task 2022-07-13T19:59:16.010Z] 19:59:16 INFO - PID 3972 | =========================================================================================================================
[task 2022-07-13T19:59:16.010Z] 19:59:16 INFO - PID 3972 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:367 | PeerConnectionImpl
[task 2022-07-13T19:59:16.011Z] 19:59:16 INFO - PID 3972 | 0.000000 | 0.000000 | Initializing PC Ctx | PeerConnectionImpl.cpp:456 | Initialize
[task 2022-07-13T19:59:16.011Z] 19:59:16 INFO - PID 3972 | 0.011000 | 0.011000 | Close | PeerConnectionImpl.cpp:2028 | Close
[task 2022-07-13T19:59:16.012Z] 19:59:16 INFO - PID 3972 | 7.997000 | 7.986000 | Destructor Invoked | PeerConnectionImpl.cpp:384 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.013Z] 19:59:16 INFO - PID 3972 | 7.997000 | 0.000000 | {0b75d043-3ac3-4ad7-8d8c-2e51f41c6f83} | PeerConnectionImpl.cpp:385 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.014Z] 19:59:16 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:392: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {0b75d043-3ac3-4ad7-8d8c-2e51f41c6f83}
[task 2022-07-13T19:59:16.016Z] 19:59:16 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{c1cc9318-dbc3-4dd9-acfd-e69020a5d57c} 1657742167747000 (id=25769803789 url=http://web-platform.test:8000/webrtc/protocol/rtp-c): Destroy
[task 2022-07-13T19:59:16.018Z] 19:59:16 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{4cdc2e09-3a95-4de6-9315-908ab27428e8} 1657742167747000 (id=25769803789 url=http://web-platform.test:8000/webrtc/protocol/rtp-c): Destroy
[task 2022-07-13T19:59:16.020Z] 19:59:16 INFO - PID 3972 | Timecard cr[Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{f4ca6f91-71a5-4b1b-bbed-08fe16a42bef} 1657742348012000 (id=25769803793 url=http://web-platform.test:8000/webrtc/protocol/rtp-d): Destroy
[task 2022-07-13T19:59:16.020Z] 19:59:16 INFO - PID 3972 | eated 1657742347.990000
[task 2022-07-13T19:59:16.022Z] 19:59:16 INFO - PID 3972 | Timestamp | Delta | Event | File | Function
[task 2022-07-13T19:59:16.023Z] 19:59:16 INFO - PID 3972 | =========================================================================================================================
[task 2022-07-13T19:59:16.025Z] 19:59:16 INFO - PID 3972 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:367 | PeerConnectionImpl
[task 2022-07-13T19:59:16.026Z] 19:59:16 INFO - PID 3972 | 0.006000 | 0.006000 | Initializing PC Ctx | PeerConnectionImpl.cpp:456 | Initialize
[task 2022-07-13T19:59:16.028Z] 19:59:16 INFO - PID 3972 | 0.018000 | 0.012000 | Close | PeerConnectionImpl.cpp:2028 | Close
[task 2022-07-13T19:59:16.029Z] 19:59:16 INFO - PID 3972 | 8.020000 | 8.002000 | Destructor Invoked | PeerConnectionImpl.cpp:384 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.030Z] 19:59:16 INFO - PID 3972 | 8.020000 | 0.000000 | {a182a97b-5230-4338-81e7-5d8657eb835a} | PeerConnectionImpl.cpp:385 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.033Z] 19:59:16 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:392: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {a182a97b-5230-4338-81e7-5d8657eb835a}
[task 2022-07-13T19:59:16.033Z] 19:59:16 INFO - PID 3972 | Timecard created 1657742167.747000
[task 2022-07-13T19:59:16.037Z] 19:59:16 INFO - PID 3972 | Timestamp | Delta | E[Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{0086ab42-9da8-4ec9-a91e-4a0ec6a46e3c} 1657742348011000 (id=25769803793 url=http://web-platform.test:8000/webrtc/protocol/rtp-d): Destroy
[task 2022-07-13T19:59:16.038Z] 19:59:16 INFO - PID 3972 | vent | File | Function
[task 2022-07-13T19:59:16.040Z] 19:59:16 INFO - PID 3972 | ====================================================================================================================================
[task 2022-07-13T19:59:16.041Z] 19:59:16 INFO - PID 3972 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:367 | PeerConnectionImpl
[task 2022-07-13T19:59:16.043Z] 19:59:16 INFO - PID 3972 | 0.000000 | 0.000000 | Initializing PC Ctx | PeerConnectionImpl.cpp:456 | Initialize
[task 2022-07-13T19:59:16.044Z] 19:59:16 INFO - PID 3972 | 0.029000 | 0.029000 | Set Remote Description | PeerConnectionImpl.cpp:1568 | SetRemoteDescription
[task 2022-07-13T19:59:16.046Z] 19:59:16 INFO - PID 3972 | 0.037000 | 0.008000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.048Z] 19:59:16 INFO - PID 3972 | 0.038000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.049Z] 19:59:16 INFO - PID 3972 | 0.038000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.051Z] 19:59:16 INFO - PID 3972 | 0.039000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.052Z] 19:59:16 INFO - PID 3972 | 0.039000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.054Z] 19:59:16 INFO - PID 3972 | 0.039000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1695 | AddIceCandidate
[task 2022-07-13T19:59:16.056Z] 19:59:16 INFO - PID 3972 | 0.039000 | 0.000000 | Create Answer | PeerConnectionImpl.cpp:1430 | CreateAnswer
[task 2022-07-13T19:59:16.057Z] 19:59:16 INFO - PID 3972 | 0.043000 | 0.004000 | Set Local Description | PeerConnectionImpl.cpp:1468 | SetLocalDescription
[task 2022-07-13T19:59:16.059Z] 19:59:16 INFO - PID 3972 | 0.045000 | 0.002000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2715 | IceGatheringStateChange
[task 2022-07-13T19:59:16.060Z] 19:59:16 INFO - PID 3972 | 0.051000 | 0.006000 | Ice Candidate gathered | PeerConnectionImpl.cpp:2557 | CandidateReady
[task 2022-07-13T19:59:16.062Z] 19:59:16 INFO - PID 3972 | 0.052000 | 0.001000 | Send Ice Candidate to content | PeerConnectionImpl.cpp:2609 | SendLocalIceCandidateToContent
[task 2022-07-13T19:59:16.063Z] 19:59:16 INFO - PID 3972 | 0.055000 | 0.003000 | Ice state: checking | PeerConnectionImpl.cpp:2639 | IceConnectionStateChange
[task 2022-07-13T19:59:16.065Z] 19:59:16 INFO - PID 3972 | 0.056000 | 0.001000 | Ice Candidate gathered | PeerConnectionImpl.cpp:2557 | CandidateReady
[task 2022-07-13T19:59:16.066Z] 19:59:16 INFO - PID 3972 | 0.057000 | 0.001000 | Send Ice Candidate to content | PeerConnectionImpl.cpp:2609 | SendLocalIceCandidateToContent
[task 2022-07-13T19:59:16.068Z] 19:59:16 INFO - PID 3972 | 0.058000 | 0.001000 | Ice Candidate gathered | PeerConnectionImpl.cpp:2557 | CandidateReady
[task 2022-07-13T19:59:16.069Z] 19:59:16 INFO - PID 3972 | 0.058000 | 0.000000 | Send Ice Candidate to content | PeerConnectionImpl.cpp:2609 | SendLocalIceCandidateToContent
[task 2022-07-13T19:59:16.071Z] 19:59:16 INFO - PID 3972 | 0.059000 | 0.001000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2718 | IceGatheringStateChange
[task 2022-07-13T19:59:16.072Z] 19:59:16 INFO - PID 3972 | 0.080000 | 0.021000 | Ice state: connected | PeerConnectionImpl.cpp:2642 | IceConnectionStateChange
[task 2022-07-13T19:59:16.074Z] 19:59:16 INFO - PID 3972 | 180.077000 | 179.997000 | Close | PeerConnectionImpl.cpp:2028 | Close
[task 2022-07-13T19:59:16.075Z] 19:59:16 INFO - PID 3972 | 188.266000 | 8.189000 | Destructor Invoked | PeerConnectionImpl.cpp:384 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.077Z] 19:59:16 INFO - PID 3972 | 188.266000 | 0.000000 | {4cdc2e09-3a95-4de6-9315-908ab27428e8} | PeerConnectionImpl.cpp:385 | ~PeerConnectionImpl
[task 2022-07-13T19:59:16.079Z] 19:59:16 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:392: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {4cdc2e09-3a95-4de6-9315-908ab27428e8}
[task 2022-07-13T19:59:16.081Z] 19:59:16 INFO - PID 3972 | Tim[Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{a182a97b-5230-4338-81e7-5d8657eb835a} 1657742347996000 (id=25769803793 url=http://web-platform.test:8000/webrtc/protocol/rtp-d): Destroy
[task 2022-07-13T19:59:16.082Z] 19:59:16 INFO - PID 3972 | ecard created 1657742167.747000
<...>
[task 2022-07-13T19:59:18.501Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: stable -> have-remote-offer
[task 2022-07-13T19:59:18.502Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:639 function nr_socket_multi_tcp_listen failed with error 3
[task 2022-07-13T19:59:18.502Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) ICE-STREAM(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0 - 87eeb567:8f85f3ac522a4b97c80bf59776401889): failed to create passive TCP host candidate: 3
[task 2022-07-13T19:59:18.503Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:639 function nr_socket_multi_tcp_listen failed with error 3
[task 2022-07-13T19:59:18.504Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) ICE-STREAM(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0 - 87eeb567:8f85f3ac522a4b97c80bf59776401889): failed to create passive TCP host candidate: 3
[task 2022-07-13T19:59:18.505Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:0 1 UDP 2122252543 10.0.0.84 54904 typ host
[task 2022-07-13T19:59:18.505Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0', no candidates.
[task 2022-07-13T19:59:18.506Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:1 1 TCP 2105524479 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.507Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0', no candidates.
[task 2022-07-13T19:59:18.508Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:0 2 UDP 2122252542 10.0.0.84 54905 typ host
[task 2022-07-13T19:59:18.508Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:1 2 TCP 2105524478 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.509Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): All candidates initialized
[task 2022-07-13T19:59:18.510Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:0 1 UDP 2122252543 10.0.0.84 54904 typ host
[task 2022-07-13T19:59:18.511Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:1 1 TCP 2105524479 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.511Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:0 2 UDP 2122252542 10.0.0.84 54905 typ host
[task 2022-07-13T19:59:18.512Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:1 2 TCP 2105524478 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.513Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content:
[task 2022-07-13T19:59:18.514Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: b9f08e0
[task 2022-07-13T19:59:18.514Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: have-remote-offer -> stable
[task 2022-07-13T19:59:18.515Z] 19:59:18 INFO - PID 3972 | (generic/EMERG) Exit UDP socket connected
[task 2022-07-13T19:59:18.516Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): StartGathering
[task 2022-07-13T19:59:18.516Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:639 function nr_socket_multi_tcp_listen failed with error 3
[task 2022-07-13T19:59:18.517Z] 19:59:18 INFO - PID 3972 | (ice/WARNING) ICE-STREAM(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0 - 3cfad813:17c6007c62b519a0bb44024e975f43ed): failed to create passive TCP host candidate: 3
[task 2022-07-13T19:59:18.517Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: b9f0a00
[task 2022-07-13T19:59:18.518Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Setting up DTLS as client
[task 2022-07-13T19:59:18.519Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: E/mtransport Couldn't disable 'PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0':2
[task 2022-07-13T19:59:18.520Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): StartChecks
[task 2022-07-13T19:59:18.520Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) starting grace period timer for 10000 ms
[task 2022-07-13T19:59:18.521Z] 19:59:18 INFO - PID 3972 | (ice/NOTICE) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) no streams with non-empty check lists
[task 2022-07-13T19:59:18.522Z] 19:59:18 INFO - PID 3972 | (ice/NOTICE) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) no streams with pre-answer requests
[task 2022-07-13T19:59:18.523Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) no checks to start, but gathering is not done yet, cancelling grace period timer
[task 2022-07-13T19:59:18.523Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: have-local-offer -> stable
[task 2022-07-13T19:59:18.524Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't start peer checks on PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e, assuming trickle ICE
[task 2022-07-13T19:59:18.525Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:0 1 UDP 2122252543 10.0.0.84 54907 typ host
[task 2022-07-13T19:59:18.526Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0', no candidates.
[task 2022-07-13T19:59:18.526Z] 19:59:18 INFO - PID 3972 | (ice/ERR) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) pairing local trickle ICE candidate host(IP4:10.0.0.84:54907/UDP)
[task 2022-07-13T19:59:18.528Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) starting grace period timer for 10000 ms
[task 2022-07-13T19:59:18.529Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport NrIceCtx(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): trickling candidate candidate:1 1 TCP 2105524479 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.529Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0', no candidates.
[task 2022-07-13T19:59:18.530Z] 19:59:18 INFO - PID 3972 | (ice/ERR) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) pairing local trickle ICE candidate host(IP4:10.0.0.84:59853/TCP) active
[task 2022-07-13T19:59:18.531Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e transport-id=transport_0', no candidates.
[task 2022-07-13T19:59:18.532Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): All candidates initialized
[task 2022-07-13T19:59:18.532Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: D/mtransport Setting up DTLS as server
[task 2022-07-13T19:59:18.532Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): StartChecks
[task 2022-07-13T19:59:18.534Z] 19:59:18 INFO - PID 3972 | (ice/INFO) ICE(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) starting grace period timer for 10000 ms
[task 2022-07-13T19:59:18.535Z] 19:59:18 INFO - PID 3972 | (ice/NOTICE) ICE(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) no streams with non-empty check lists
[task 2022-07-13T19:59:18.535Z] 19:59:18 INFO - PID 3972 | (ice/NOTICE) ICE(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): peer (PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e:default) no streams with pre-answer requests
[task 2022-07-13T19:59:18.536Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:0 1 UDP 2122252543 10.0.0.84 54907 typ host
[task 2022-07-13T19:59:18.537Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content: candidate:1 1 TCP 2105524479 10.0.0.84 9 typ host tcptype active
[task 2022-07-13T19:59:18.538Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2602: Passing local candidate to content:
[task 2022-07-13T19:59:18.538Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: b9f0d60
[task 2022-07-13T19:59:18.539Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: stable -> have-local-offer
[task 2022-07-13T19:59:18.540Z] 19:59:18 INFO - PID 3972 | (generic/EMERG) Exit UDP socket connected
[task 2022-07-13T19:59:18.540Z] 19:59:18 INFO - PID 3972 | [Child 8748: Socket Thread]: I/mtransport NrIceCtx(PC:{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e): StartGathering
[task 2022-07-13T19:59:18.541Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: b9f0e80
[task 2022-07-13T19:59:18.541Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: stable -> have-remote-offer
[task 2022-07-13T19:59:18.542Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: b9f0ee0
[task 2022-07-13T19:59:18.543Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: E/jsep [{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: Answer changed id for extmap attribute at level 0 (urn:ietf:params:rtp-hdrext:sdes:mid) from 3 to 1.
[task 2022-07-13T19:59:18.544Z] 19:59:18 INFO - PID 3972 | [Child 8748, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, dom::PCError::InvalidAccessError) failed with result 0x80070057 (NS_ERROR_ILLEGAL_VALUE): file /builds/worker/checkouts/gecko/dom/media/webrtc/jsep/JsepSessionImpl.cpp:982
[task 2022-07-13T19:59:18.544Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1608: SetRemoteDescription: pc = {93f524ca-44f0-4710-98bd-ccb34e6b684e}, error = Answer changed id for extmap attribute at level 0 (urn:ietf:params:rtp-hdrext:sdes:mid) from 3 to 1.
[task 2022-07-13T19:59:18.545Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2045: Close: Closing PeerConnectionImpl {93f524ca-44f0-4710-98bd-ccb34e6b684e}; ending call
[task 2022-07-13T19:59:18.546Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{93f524ca-44f0-4710-98bd-ccb34e6b684e} 1657742358484000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: have-local-offer -> closed
[task 2022-07-13T19:59:18.547Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2045: Close: Closing PeerConnectionImpl {67b8e984-60b6-49b9-ae14-8880af7083ac}; ending call
[task 2022-07-13T19:59:18.547Z] 19:59:18 INFO - PID 3972 | [Child 8748: Main Thread]: I/jsep [{67b8e984-60b6-49b9-ae14-8880af7083ac} 1657742358485000 (id=25769803797 url=http://web-platform.test:8000/webrtc/protocol/rtp-e]: have-remote-offer -> closed
[task 2022-07-13T19:59:18.548Z] 19:59:18 INFO - PID 3972 | [Child 8748: WebrtcCallThread #1]: E/signaling [WebrtcCallThread #1|WebrtcAudioSessionConduit] AudioConduit.cpp:646: SendSenderRtcp RTCP SR Packet Send Failed
[task 2022-07-13T19:59:18.548Z] 19:59:18 INFO - PID 3972 | [Child 8748: GraphRunner]: E/signaling [GraphRunner|WebrtcAudioSessionConduit] AudioConduit.cpp:465: GetAudioFrame Engine not Receiving
[task 2022-07-13T19:59:18.549Z] 19:59:18 INFO - Got content assert count 2
[task 2022-07-13T19:59:18.553Z] 19:59:18 INFO - TEST-UNEXPECTED-FAIL | /webrtc/protocol/rtp-extension-support.html | assertion count 2 is more than expected 0 assertions
[task 2022-07-13T19:59:18.553Z] 19:59:18 INFO - ......
[task 2022-07-13T19:59:18.553Z] 19:59:18 INFO - TEST-OK | /webrtc/protocol/rtp-extension-support.html | took 10185ms
[task 2022-07-13T19:59:18.554Z] 19:59:18 INFO - TEST-START | /webrtc/protocol/rtp-payloadtypes.html
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=403659247&repo=mozilla-beta
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•