Closed Bug 1292976 Opened 8 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/media/tests/mochitest/test_peerConnection_restartIce.html | application timed out after 330 seconds with no output

Categories

(Core :: WebRTC: Networking, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE
Tracking Status
firefox50 --- affected
firefox51 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: bulk-close-intermittents, intermittent-failure)

Hmm something apparently calls some kind of abort() function here, which then results in a test timeout laster:

09:30:28     INFO -  (ice/NOTICE) ICE(PC:1470414627968384 (id=2147483869 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIce.html)): peer (PC:1470414627968384 (id=2147483869 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIce.html):default) no streams with pre-answer requests
09:30:28     INFO -  (ice/NOTICE) ICE(PC:1470414627968384 (id=2147483869 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIce.html)): peer (PC:1470414627968384 (id=2147483869 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIce.html):default) no checks to start
09:30:28     INFO -  Redirecting call to abort() to mozalloc_abort
09:30:29     INFO -  ###!!! [Parent][MessageChannel] Error: (msgtype=0x2E007D,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
09:35:59     INFO -  2111 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIce.html | A valid string reason is expected
09:35:59     INFO -  2112 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIce.html | Reason cannot be empty
09:35:59     INFO -  2113 INFO Skipping ICE Server for this test
backlog: --- → webrtc/webaudio+
Rank: 35
Component: WebRTC → WebRTC: Networking
Priority: -- → P3
Looks like something has gone wrong in mozilla::layers::ImageContainer::GetCurrentImages when it tries to unlock a mutex.

09:36:18     INFO -   0  libmozglue.dylib!mozalloc_abort(char const*) [mozalloc_abort.cpp:192132a4cbc7 : 33 + 0x0]
09:36:18     INFO -      rax = 0x0000000000000001   rdx = 0x00007fff7603c1f8
09:36:18     INFO -      rcx = 0x0000000000000000   rbx = 0x00007fff7603cc50
09:36:18     INFO -      rsi = 0x001c5700001c5700   rdi = 0x001c5600001c5703
09:36:18     INFO -      rbp = 0x00000001221806d0   rsp = 0x00000001221806c0
09:36:18     INFO -       r8 = 0x0000000122180650    r9 = 0x0000000122181000
09:36:18     INFO -      r10 = 0x0000000122180690   r11 = 0x0000000000000246
09:36:18     INFO -      r12 = 0x8000000400000000   r13 = 0x0000000122180d50
09:36:18     INFO -      r14 = 0x0000000110f30000   r15 = 0x0000000000000040
09:36:18     INFO -      rip = 0x0000000110b4d3a4
09:36:18     INFO -      Found by: given as instruction pointer in context
09:36:18     INFO -   1  libmozglue.dylib!abort [mozalloc_abort.cpp:192132a4cbc7 : 80 + 0xc]
09:36:18     INFO -      rbx = 0x0000000110f300c0   rbp = 0x00000001221806e0
09:36:18     INFO -      rsp = 0x00000001221806e0   r12 = 0x8000000400000000
09:36:18     INFO -      r13 = 0x0000000122180d50   r14 = 0x0000000110f30000
09:36:18     INFO -      r15 = 0x0000000000000040   rip = 0x0000000110b4d3d0
09:36:18     INFO -      Found by: call frame info
09:36:18     INFO -   2  libsystem_pthread.dylib!__pthread_abort + 0x31
09:36:18     INFO -      rbx = 0x0000000110f300c0   rbp = 0x00000001221806f0
09:36:18     INFO -      rsp = 0x00000001221806f0   r12 = 0x8000000400000000
09:36:18     INFO -      r13 = 0x0000000122180d50   r14 = 0x0000000110f30000
09:36:18     INFO -      r15 = 0x0000000000000040   rip = 0x00007fff8f716e1f
09:36:18     INFO -      Found by: call frame info
09:36:18     INFO -   3  libsystem_pthread.dylib!__pthread_abort_reason + 0xb4
09:36:18     INFO -      rbp = 0x00000001221807e0   rsp = 0x0000000122180700
09:36:18     INFO -      rip = 0x00007fff8f716ed3
09:36:18     INFO -      Found by: previous frame's frame pointer
09:36:18     INFO -   4  libsystem_pthread.dylib!pthread_mutex_unlock + 0xf6
09:36:18     INFO -      rbp = 0x0000000122180820   rsp = 0x00000001221807f0
09:36:18     INFO -      rip = 0x00007fff8f711aa1
09:36:18     INFO -      Found by: previous frame's frame pointer
09:36:18     INFO -   5  XUL!mozilla::layers::ImageContainer::GetCurrentImages(nsTArray<mozilla::layers::ImageContainer::OwningImage>*, unsigned int*) [ReentrantMonitor.h:192132a4cbc7 : 74 + 0x5]
09:36:18     INFO -      rbp = 0x0000000122180850   rsp = 0x0000000122180830
09:36:18     INFO -      rip = 0x0000000107d956ac
09:36:18     INFO -      Found by: previous frame's frame pointer
Makes we wonder if this related to bug 1292785 and bug 1292774?
See Also: → 1292785, 1292774
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
ASAN crash (read of unknown address) in copy_and_extend_plane /builds/worker/workspace/build/src/media/libvpx/libvpx/vp8/common/extend.c:37:23
We've had crashes here before.  This is a buffer-overrun (bounds) failure almost certainly.  In the past this sort of problem was due to resolution changes that hadn't rippled down into the VPX encoder when the frame got down there.   These buffers are sized based on the resolution the frame is configured to.  Alternatively something might have trashed the size between when it was checked and here (very unlikely).
So the last crash reported appears to be a separate issue, and not a timeout really.
Yeah, welcome to the new world order, where everybody insists on shoving PIDs and paths into every line of output, and doing their crashing and asserting OMT, and the only thing we have that we can get a suggestible bug out of is the main thread timeout. Sorry, you'll have to take it from there, since neither treeherder nor the people creating the output are willing to take all the crap that breaks bug suggestions out.
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Depends on: gUM_timeout
You need to log in before you can comment on or make changes to this bug.