Closed Bug 1292976 Opened 9 years ago Closed 8 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: 9 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.
Status: REOPENED → RESOLVED
Closed: 9 years ago8 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.