1836782 - Intermittent SUMMARY: AddressSanitizer: access-violation (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff540f8) in DllGetClassObject+0x17688

Status: RESOLVED INCOMPLETE

(Core :: WebRTC: Audio/Video, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2023-06-05T14:17:04.671Z] 14:17:04     INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_peerConnection_extmapRenegotiation.html
[task 2023-06-05T14:17:05.047Z] 14:17:05     INFO - GECKO(7448) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2023-06-05T14:17:05.057Z] 14:17:05     INFO - GECKO(7448) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2023-06-05T14:17:05.204Z] 14:17:05     INFO - GECKO(7448) | Timecard created 1685974623.817000
[task 2023-06-05T14:17:05.214Z] 14:17:05     INFO - GECKO(7448) |  Timestamp   | Delta       | Event                                  | File                         | Function
[task 2023-06-05T14:17:05.216Z] 14:17:05     INFO - GECKO(7448) | ====================================================================================================================================
[task 2023-06-05T14:17:05.216Z] 14:17:05     INFO - GECKO(7448) |     0.000000 |    0.000000 | Constructor Completed                  | PeerConnectionImpl.cpp:387   | PeerConnectionImpl
[task 2023-06-05T14:17:05.218Z] 14:17:05     INFO - GECKO(7448) |     0.001000 |    0.001000 | Initializing PC Ctx                    | PeerConnectionImpl.cpp:472   | Initialize
[task 2023-06-05T14:17:05.220Z] 14:17:05     INFO - GECKO(7448) |     0.046000 |    0.045000 | Create Offer                           | PeerConnectionImpl.cpp:1580  | CreateOffer
[task 2023-06-05T14:17:05.221Z] 14:17:05     INFO - GECKO(7448) |     0.059000 |    0.013000 | Set Local Description                  | PeerConnectionImpl.cpp:1684  | SetLocalDescription
[task 2023-06-05T14:17:05.225Z] 14:17:05     INFO - GECKO(7448) |     0.119000 |    0.060000 | Ice gathering state: gathering         | PeerConnectionImpl.cpp:3429  | IceGatheringStateChange
[task 2023-06-05T14:17:05.225Z] 14:17:05     INFO - GECKO(7448) |     0.120000 |    0.001000 | Ice Candidate gathered                 | PeerConnectionImpl.cpp:3256  | CandidateReady
[task 2023-06-05T14:17:05.227Z] 14:17:05     INFO - GECKO(7448) |     0.123000 |    0.003000 | Send Ice Candidate to content          | PeerConnectionImpl.cpp:3320  | SendLocalIceCandidateToContent
[task 2023-06-05T14:17:05.227Z] 14:17:05     INFO - GECKO(7448) |     0.124000 |    0.001000 | Ice Candidate gathered                 | PeerConnectionImpl.cpp:3256  | CandidateReady
[task 2023-06-05T14:17:05.228Z] 14:17:05     INFO - GECKO(7448) |     0.126000 |    0.002000 | Send Ice Candidate to content          | PeerConnectionImpl.cpp:3320  | SendLocalIceCandidateToContent
[task 2023-06-05T14:17:05.230Z] 14:17:05     INFO - GECKO(7448) |     0.126000 |    0.000000 | Ice Candidate gathered                 | PeerConnectionImpl.cpp:3256  | CandidateReady
[task 2023-06-05T14:17:05.231Z] 14:17:05     INFO - GECKO(7448) |     0.128000 |    0.002000 | Send Ice Candidate to content          | PeerConnectionImpl.cpp:3320  | SendLocalIceCandidateToContent
[task 2023-06-05T14:17:05.232Z] 14:17:05     INFO - GECKO(7448) |     0.130000 |    0.002000 | Ice Candidate gathered                 | PeerConnectionImpl.cpp:3256  | CandidateReady
[task 2023-06-05T14:17:05.233Z] 14:17:05     INFO - GECKO(7448) |     0.131000 |    0.001000 | Send Ice Candidate to content          | PeerConnectionImpl.cpp:3320  | SendLocalIceCandidateToContent
[task 2023-06-05T14:17:05.234Z] 14:17:05     INFO - GECKO(7448) |     0.131000 |    0.000000 | Ice Candidate gathered                 | PeerConnectionImpl.cpp:3256  | CandidateReady
[task 2023-06-05T14:17:05.235Z] 14:17:05     INFO - GECKO(7448) |     0.133000 |    0.002000 | Send Ice Candidate to content          | PeerConnectionImpl.cpp:3320  | SendLocalIceCandidateToContent
[task 2023-06-05T14:17:05.237Z] 14:17:05     INFO - GECKO(7448) |     0.133000 |    0.000000 | Ice gathering state: complete          | PeerConnectionImpl.cpp:3432  | IceGatheringStateChange
[task 2023-06-05T14:17:05.238Z] 14:17:05     INFO - GECKO(7448) |     0.148000 |    0.015000 | Close                                  | PeerConnectionImpl.cpp:2596  | Close
[task 2023-06-05T14:17:05.240Z] 14:17:05     INFO - GECKO(7448) |     1.387000 |    1.239000 | Destructor Invoked                     | PeerConnectionImpl.cpp:404   | ~PeerConnectionImpl
[task 2023-06-05T14:17:05.241Z] 14:17:05     INFO - GECKO(7448) |     1.387000 |    0.000000 | {b8c28ce1-ed76-4c08-a778-cea4d7bab0d8} | PeerConnectionImpl.cpp:405   | ~PeerConnectionImpl
[task 2023-06-05T14:17:05.243Z] 14:17:05     INFO - GECKO(7448) | [Child 8896: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:412: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {b8c28ce1-ed76-4c08-a778-cea4d7bab0d8}
<...>
[task 2023-06-05T14:17:20.356Z] 14:17:20     INFO - GECKO(7448) | [Child 8896: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:3904: OnStunAddrsAvailable: receiving (3) stun addrs
[task 2023-06-05T14:17:20.895Z] 14:17:20     INFO - GECKO(7448) | =================================================================
[task 2023-06-05T14:17:20.895Z] 14:17:20    ERROR - GECKO(7448) | ==8896==ERROR: AddressSanitizer: access-violation on unknown address 0x018f283afbf0 (pc 0x7ffa6f7f40f9 bp 0x007fd597f1c9 sp 0x007fd597f160 T1073)
[task 2023-06-05T14:17:20.897Z] 14:17:20     INFO - GECKO(7448) | ==8896==The signal is caused by a READ memory access.
[task 2023-06-05T14:17:21.274Z] 14:17:21     INFO - GECKO(7448) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2023-06-05T14:17:21.276Z] 14:17:21     INFO - GECKO(7448) | (ice/INFO) ICE(PC:{a9365a70-04d0-445b-a8e3-c97077484f7c} 1685974630398000 (id=6442451051 url=https://example.com/tests/dom/media/webrtc/tests/mochitests/test_peerConnection_extmapRenegotiation.html))/STREAM(PC:{a9365a70-04d0-445b-a8e3-c97077484f7c} 1685974630398000 (id=6442451051 url=https://example.com/tests/dom/media/webrtc/tests/mochitests/test_peerConnection_extmapRenegotiation.html) transport-id=transport_0 - bb1ff505:d6580b43c7296521f5da5d463ed0b676)/COMP(1): Consent refreshed
[task 2023-06-05T14:17:21.515Z] 14:17:21     INFO - GECKO(7448) | [Child 8896: WebrtcCallThread #1]: E/signaling [WebrtcCallThread #1|WebrtcAudioSessionConduit] AudioConduit.cpp:730: AudioConduit 11c544c1c680: RTCP RR Packet Send Failed
[task 2023-06-05T14:17:21.628Z] 14:17:21     INFO - GECKO(7448) | ==8896==WARNING: Failed to use and restart external symbolizer!
[task 2023-06-05T14:17:21.649Z] 14:17:21     INFO - GECKO(7448) |     #0 0x7ffa6f7f40f8 in DllGetClassObject+0x17688 (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff540f8)
[task 2023-06-05T14:17:21.650Z] 14:17:21     INFO - GECKO(7448) |     #1 0x7ffa6f7f56f7 in DllGetClassObject+0x18c87 (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff556f7)
[task 2023-06-05T14:17:21.651Z] 14:17:21     INFO - GECKO(7448) |     #2 0x7ffa6f7ec554 in DllGetClassObject+0xfae4 (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff4c554)
[task 2023-06-05T14:17:21.651Z] 14:17:21     INFO - GECKO(7448) |     #3 0x7ffa6f7f9f8f in DllGetClassObject+0x1d51f (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff59f8f)
[task 2023-06-05T14:17:21.652Z] 14:17:21     INFO - GECKO(7448) |     #4 0x7ffa96999362 in recalloc+0xa2 (C:\Windows\System32\ucrtbase.dll+0x180029362)
[task 2023-06-05T14:17:21.653Z] 14:17:21     INFO - GECKO(7448) |     #5 0x7ffa4ae5b713 in __asan::AsanThread::ThreadStart /builds/worker/fetches/llvm-project/compiler-rt/lib/asan/asan_thread.cpp:277
[task 2023-06-05T14:17:21.653Z] 14:17:21     INFO - GECKO(7448) |     #6 0x7ffa97f5269c in BaseThreadInitThunk+0x1c (C:\Windows\System32\KERNEL32.DLL+0x18001269c)
[task 2023-06-05T14:17:21.654Z] 14:17:21     INFO - GECKO(7448) |     #7 0x7ffa6fb94223 in patched_BaseThreadInitThunk /builds/worker/checkouts/gecko/toolkit/xre/dllservices/mozglue/WindowsDllBlocklist.cpp:616
[task 2023-06-05T14:17:21.655Z] 14:17:21     INFO - GECKO(7448) |     #8 0x7ffa98f0a9f7 in RtlUserThreadStart+0x27 (C:\Windows\SYSTEM32\ntdll.dll+0x18005a9f7)
[task 2023-06-05T14:17:21.655Z] 14:17:21     INFO - GECKO(7448) | AddressSanitizer can not provide additional info.
[task 2023-06-05T14:17:21.656Z] 14:17:21     INFO - GECKO(7448) | SUMMARY: AddressSanitizer: access-violation (C:\Program Files\WindowsApps\Microsoft.VP9VideoExtensions_1.0.50901.0_x64__8wekyb3d8bbwe\x64\msvpxenc.dll+0x11ff540f8) in DllGetClassObject+0x17688
[task 2023-06-05T14:17:21.657Z] 14:17:21     INFO - GECKO(7448) | Thread T1073 created by T1062 here:
[task 2023-06-05T14:17:21.709Z] 14:17:21     INFO - GECKO(7448) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2023-06-05T14:17:21.711Z] 14:17:21     INFO - GECKO(7448) | (ice/INFO) ICE(PC:{a46ea2fd-13df-4538-b8ef-3fb436941fe5} 1685974625385000 (id=6442451051 url=https://example.com/tests/dom/media/webrtc/tests/mochitests/test_peerConnection_extmapRenegotiation.html))/STREAM(PC:{a46ea2fd-13df-4538-b8ef-3fb436941fe5} 1685974625385000 (id=6442451051 url=https://example.com/tests/dom/media/webrtc/tests/mochitests/test_peerConnection_extmapRenegotiation.html) transport-id=transport_0 - dcafe4f9:b6fa826b4cd14e21aff2f8c08be62066)/COMP(1): Consent refreshed
<...>

Comment 1

[Jim Mathies [:jimm]]

Hey John, any thoughts on this vp9 platform libraries?