Closed Bug 1691936 Opened 5 years ago Closed 4 years ago

Perma linux wpt [tier 2] /webrtc/RTCPeerConnection-relay-canvas.https.html | Two PeerConnections relaying a canvas source - promise_test: Unhandled rejection with value: object "TypeError: can't access property "getTracks", stream is undefined"

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED FIXED
87 Branch
Tracking Status
firefox-esr78 --- unaffected
firefox85 --- unaffected
firefox86 --- unaffected
firefox87 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jgraham)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

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


[task 2021-02-10T12:19:20.239Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1439: SetRemoteDescription: pc = {aa4e042c-86ad-4f0e-928f-242df7e28b80}, asking JS to create transceiver
[task 2021-02-10T12:19:20.240Z] 12:19:20     INFO - TEST-START | /webrtc/RTCPeerConnection-relay-canvas.https.html
[task 2021-02-10T12:19:20.240Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1439: SetRemoteDescription: pc = {aa4e042c-86ad-4f0e-928f-242df7e28b80}, asking JS to create transceiver
[task 2021-02-10T12:19:20.241Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1439: SetRemoteDescription: pc = {aa4e042c-86ad-4f0e-928f-242df7e28b80}, asking JS to create transceiver
[task 2021-02-10T12:19:20.242Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1439: SetRemoteDescription: pc = {aa4e042c-86ad-4f0e-928f-242df7e28b80}, asking JS to create transceiver
[task 2021-02-10T12:19:20.242Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.243Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.243Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.244Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.245Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.245Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.246Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.246Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.247Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.247Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.248Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.249Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.250Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.250Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.251Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.251Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.252Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0xf485af40
[task 2021-02-10T12:19:20.253Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.253Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.254Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.254Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.255Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.255Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.256Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.256Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.257Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.258Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.258Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.259Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.259Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.260Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.261Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2042: DeliverPacket DeliverPacket Failed, 2
[task 2021-02-10T12:19:20.262Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/signaling [Socket Thread|WebrtcVideoSessionConduit] VideoConduit.cpp:2143: ReceivedRTCPPacket RTCP Processing Failed
[task 2021-02-10T12:19:20.262Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/jsep [{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc)]: have-remote-offer -> stable
[task 2021-02-10T12:19:20.263Z] 12:19:20     INFO - PID 25328 | (generic/INFO) Exit UDP socket connected
[task 2021-02-10T12:19:20.263Z] 12:19:20     INFO - PID 25328 | (generic/INFO) Exit UDP socket connected
[task 2021-02-10T12:19:20.264Z] 12:19:20     INFO - PID 25328 | [Child 29641: Socket Thread]: E/mtransport Couldn't disable 'PC:{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc) transport-id=transport_1':2
[task 2021-02-10T12:19:20.264Z] 12:19:20     INFO - PID 25328 | (ice/INFO) ICE-PEER(PC:{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc):default): all checks completed success=1 fail=0
[task 2021-02-10T12:19:20.265Z] 12:19:20     INFO - PID 25328 | (ice/ERR) ICE(PC:{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc)): peer (PC:{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc):default) in nr_ice_peer_ctx_start_checks2 all streams were done
[task 2021-02-10T12:19:20.265Z] 12:19:20     INFO - Closing window 138
<...>
[task 2021-02-10T12:19:20.543Z] 12:19:20     INFO - PID 25328 | [Child 29641: Main Thread]: I/jsep [{aa4e042c-86ad-4f0e-928f-242df7e28b80} 1612959559073330 (id=27917287436 url=about:srcdoc)]: stable -> closed
[task 2021-02-10T12:19:20.543Z] 12:19:20     INFO - PID 25328 | Timecard created 1612959558.757522
[task 2021-02-10T12:19:20.544Z] 12:19:20     INFO - PID 25328 |  Timestamp   | Delta       | Event                          | File                         | Function
[task 2021-02-10T12:19:20.544Z] 12:19:20     INFO - PID 25328 | ======================================================================================================================
[task 2021-02-10T12:19:20.545Z] 12:19:20     INFO - PID 25328 |     0.000042 |    0.000042 | Constructor Completed          | PeerConnectionImpl.cpp:326   | PeerConnectionImpl
[task 2021-02-10T12:19:20.546Z] 12:19:20     INFO - PID 25328 |     0.000178 |    0.000136 | Initializing PC Ctx            | PeerConnectionImpl.cpp:444   | Initialize
[task 2021-02-10T12:19:20.546Z] 12:19:20     INFO - PID 25328 |     0.043113 |    0.042935 | Create Offer                   | PeerConnectionImpl.cpp:1206  | CreateOffer
[task 2021-02-10T12:19:20.547Z] 12:19:20     INFO - PID 25328 |     0.046033 |    0.002920 | Set Local Description          | PeerConnectionImpl.cpp:1275  | SetLocalDescription
[task 2021-02-10T12:19:20.547Z] 12:19:20     INFO - PID 25328 |     0.059941 |    0.013908 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2523  | IceGatheringStateChange
[task 2021-02-10T12:19:20.548Z] 12:19:20     INFO - PID 25328 |     0.068156 |    0.008215 | Ice gathering state: complete  | PeerConnectionImpl.cpp:2526  | IceGatheringStateChange
[task 2021-02-10T12:19:20.549Z] 12:19:20     INFO - PID 25328 |     0.073641 |    0.005485 | Set Remote Description         | PeerConnectionImpl.cpp:1375  | SetRemoteDescription
[task 2021-02-10T12:19:20.549Z] 12:19:20     INFO - PID 25328 |     0.078183 |    0.004542 | Add Ice Candidate              | PeerConnectionImpl.cpp:1523  | AddIceCandidate
[task 2021-02-10T12:19:20.550Z] 12:19:20     INFO - PID 25328 |     0.079386 |    0.001203 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.551Z] 12:19:20     INFO - PID 25328 |     0.079568 |    0.000182 | Add Ice Candidate              | PeerConnectionImpl.cpp:1523  | AddIceCandidate
[task 2021-02-10T12:19:20.551Z] 12:19:20     INFO - PID 25328 |     0.081464 |    0.001896 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.552Z] 12:19:20     INFO - PID 25328 |     0.081794 |    0.000330 | Add Ice Candidate              | PeerConnectionImpl.cpp:1523  | AddIceCandidate
[task 2021-02-10T12:19:20.552Z] 12:19:20     INFO - PID 25328 |     0.082188 |    0.000394 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.553Z] 12:19:20     INFO - PID 25328 |     0.082526 |    0.000338 | Add Ice Candidate              | PeerConnectionImpl.cpp:1523  | AddIceCandidate
[task 2021-02-10T12:19:20.554Z] 12:19:20     INFO - PID 25328 |     0.082798 |    0.000272 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.554Z] 12:19:20     INFO - PID 25328 |     0.083045 |    0.000247 | Add Ice Candidate              | PeerConnectionImpl.cpp:1523  | AddIceCandidate
[task 2021-02-10T12:19:20.558Z] 12:19:20     INFO - PID 25328 |     0.084102 |    0.001057 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.559Z] 12:19:20     INFO - PID 25328 |     0.084107 |    0.000005 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.559Z] 12:19:20     INFO - PID 25328 |     0.084445 |    0.000338 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.560Z] 12:19:20     INFO - PID 25328 |     0.084451 |    0.000006 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.592Z] 12:19:20     INFO - PID 25328 |     0.084862 |    0.000411 | Create Offer                   | PeerConnectionImpl.cpp:1206  | CreateOffer
[task 2021-02-10T12:19:20.594Z] 12:19:20     INFO - PID 25328 |     0.086386 |    0.001524 | Set Local Description          | PeerConnectionImpl.cpp:1275  | SetLocalDescription
[task 2021-02-10T12:19:20.595Z] 12:19:20     INFO - PID 25328 |     0.092026 |    0.005640 | Ice state: checking            | PeerConnectionImpl.cpp:2467  | IceConnectionStateChange
[task 2021-02-10T12:19:20.596Z] 12:19:20     INFO - PID 25328 |     0.093602 |    0.001576 | Ice state: connected           | PeerConnectionImpl.cpp:2470  | IceConnectionStateChange
[task 2021-02-10T12:19:20.597Z] 12:19:20     INFO - PID 25328 |     0.125542 |    0.031940 | Set Remote Description         | PeerConnectionImpl.cpp:1375  | SetRemoteDescription
[task 2021-02-10T12:19:20.597Z] 12:19:20     INFO - PID 25328 |     0.138039 |    0.012497 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.598Z] 12:19:20     INFO - PID 25328 |     0.138069 |    0.000030 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.598Z] 12:19:20     INFO - PID 25328 |     0.138089 |    0.000020 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.599Z] 12:19:20     INFO - PID 25328 |     0.138110 |    0.000021 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.600Z] 12:19:20     INFO - PID 25328 |     0.140706 |    0.002596 | Create Offer                   | PeerConnectionImpl.cpp:1206  | CreateOffer
[task 2021-02-10T12:19:20.600Z] 12:19:20     INFO - PID 25328 |     0.141380 |    0.000674 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.601Z] 12:19:20     INFO - PID 25328 |     0.141389 |    0.000009 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.602Z] 12:19:20     INFO - PID 25328 |     0.141410 |    0.000021 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.603Z] 12:19:20     INFO - PID 25328 |     0.141414 |    0.000004 | Done looking up mDNS name      | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.603Z] 12:19:20     INFO - PID 25328 |     0.141960 |    0.000546 | Set Local Description          | PeerConnectionImpl.cpp:1275  | SetLocalDescription
[task 2021-02-10T12:19:20.604Z] 12:19:20     INFO - PID 25328 |     0.173857 |    0.031897 | Set Remote Description         | PeerConnectionImpl.cpp:1375  | SetRemoteDescription
[task 2021-02-10T12:19:20.604Z] 12:19:20     INFO - PID 25328 |     0.192933 |    0.019076 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.605Z] 12:19:20     INFO - PID 25328 |     0.192957 |    0.000024 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.606Z] 12:19:20     INFO - PID 25328 |     0.192971 |    0.000014 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard
[task 2021-02-10T12:19:20.607Z] 12:19:20     INFO - 
[task 2021-02-10T12:19:20.607Z] 12:19:20     INFO - TEST-UNEXPECTED-FAIL | /webrtc/RTCPeerConnection-relay-canvas.https.html | Two PeerConnections relaying a canvas source - promise_test: Unhandled rejection with value: object "TypeError: can't access property "getTracks", stream is undefined"
[task 2021-02-10T12:19:20.608Z] 12:19:20     INFO - TEST-OK | /webrtc/RTCPeerConnection-relay-canvas.https.html | took 316ms
[task 2021-02-10T12:19:20.609Z] 12:19:20     INFO - PID 25328 |     0.192986 |    0.000015 | Look up mDNS name              | PeerConnectionImpl.cpp:1811  | StampTimecard```

James, this looks to be caused by yesterday's wpt-sync:
https://hg.mozilla.org/mozilla-central/rev/a864e0fd2f0029ec4e3f468991eaed060379be63#l219.10

Could you please take a look?

Flags: needinfo?(james)
Assignee: nobody → james
Status: NEW → ASSIGNED
Flags: needinfo?(james)
Pushed by archaeopteryx@coole-files.de: https://hg.mozilla.org/integration/autoland/rev/bfedcceccd0f Update metadata for linux 32, r=aryx DONTBUILD

Set release status flags based on info from the regressing bug 1691629

Status: ASSIGNED → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → 87 Branch
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: