New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 831818 link

Starred by 3 users

Issue metadata

Status: Available
Owner: ----
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Mac
Pri: 2
Type: Bug-Regression

Blocked on:
issue 835585



Sign in to add a comment

Multiple browser_tests flaky time out on mac_chromium_rel_ng

Project Member Reported by ynovikov@chromium.org, Apr 11 2018

Issue description

Cc: tapted@chromium.org benwells@chromium.org tommi@chromium.org olka@chromium.org mlamouri@chromium.org sergeyu@chromium.org sa...@chromium.org asargent@chromium.org beccahughes@chromium.org maxmorin@chromium.org
Components: -UI>Browser Platform>Apps>BrowserTag Internals>Media Blink>Media
Going to try to find owners for the specific tests, otherwise this is never going to be triaged.

https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/23590
[87/877] MediaEngagementBrowserTest.SessionNewTabSameURL (TIMED OUT)
[88/877] MediaEngagementPrerenderBrowserTest.Ignored (TIMED OUT)
[90/877] UnifiedAutoplayBrowserTest.OpenCrossDomainFromMenu (TIMED OUT)
[91/877] UnifiedAutoplayBrowserTest.OpenFromRendererGesture (TIMED OUT)
[398/877] FileSystemApiTest.FileSystemApiOpenExistingFileWithWriteTest (TIMED OUT)
[399/877] FileSystemApiTest.RequestFileSystem_NotChromeOS (TIMED OUT)
[724/877] MSE_ClearKey/EncryptedMediaTest.Playback_VideoAudio_WebM/0 (TIMED OUT)
[725/877] MSE_ClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM/0 (TIMED OUT)
[727/877] MSE_ExternalClearKey/EncryptedMediaTest.Playback_AudioClearVideo_WebM/0 (TIMED OUT)
[730/877] MSE_ExternalClearKey/EncryptedMediaTest.Playback_VP9Video_WebM_Subsample/0 (TIMED OUT)
[879/887] FileSystemApiTest.RequestFileSystem_NotChromeOS (TIMED OUT)
[881/887] MSE_ClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM/0 (TIMED OUT)
[883/887] MSE_ExternalClearKey/EncryptedMediaTest.Playback_VP9Video_WebM_Subsample/0 (TIMED OUT)
[889/890] MSE_ClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM/0 (TIMED OUT)
[891/891] MSE_ClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM/0 (TIMED OUT)

https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/23525
[398/877] FileSystemApiTest.FileSystemApiOpenExistingFileWithWriteTest (TIMED OUT)
[399/877] FileSystemApiTest.RequestFileSystem_NotChromeOS (TIMED OUT)
[725/877] MSE_ClearKey/EncryptedMediaTest.Playback_VideoOnly_MP4_VP9/0 (TIMED OUT)
[727/877] SRC_ExternalClearKey/EncryptedMediaTest.Playback_AudioClearVideo_WebM/0 (TIMED OUT)
[745/877] MediaEngagementAutoplayBrowserTest.BypassAutoplayFrameHighEngagement_Delegation/1 (TIMED OUT)
[746/877] MediaEngagementAutoplayBrowserTest.DoNotBypassAutoplayFrameLowEngagement_NoDelegation/0 (TIMED OUT)
[747/877] MediaEngagementAutoplayBrowserTest.ClearEngagementOnNavigation/0 (TIMED OUT)
[880/884] MSE_ClearKey/EncryptedMediaTest.Playback_VideoOnly_MP4_VP9/0 (TIMED OUT)
[885/885] MSE_ClearKey/EncryptedMediaTest.Playback_VideoOnly_MP4_VP9/0 (TIMED OUT)
[886/886] MSE_ClearKey/EncryptedMediaTest.Playback_VideoOnly_MP4_VP9/0 (TIMED OUT)

https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/23446
[9/793] AppWindowAPITest.TestSetBounds (TIMED OUT)
[10/793] AppEventPageTest.OnSuspendUseStorageApi (TIMED OUT)
[11/793] AppViewTest.TestAppViewWithUndefinedDataShouldSucceed (TIMED OUT)
[353/793] FileSystemApiTest.FileSystemApiSaveExistingFileTest (TIMED OUT)
[354/793] FileSystemApiTest.FileSystemApiSaveCancelTest (TIMED OUT)
[645/793] SRC_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 (TIMED OUT)
[800/800] SRC_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 (TIMED OUT)
[801/801] SRC_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 (TIMED OUT)
[802/802] SRC_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 (TIMED OUT)


Comment 2 by awdf@chromium.org, Apr 16 2018

Labels: -Pri-2 Pri-1
This just caused my CL to not pass the CQ, at https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/25623

Logs from failing test:

[ RUN      ] MSE_ClearKey/EncryptedMediaTest.Playback_VideoOnly_MP4_MDAT/0
[32402:4867:0416/102428.443153:WARNING:notification_platform_bridge_mac.mm(509)] AlertNotificationService: XPC connection invalidated.
2018-04-16 10:24:28.544 browser_tests[32402:466821] *** Owner supplied to -[NSTrackingArea initWithRect:options:owner:userInfo:] referenced a deallocating object. Tracking area behavior is undefined. Break on NSTrackingAreaDeallocatingOwnerError to debug.
[32402:775:0416/102428.838341:WARNING:gaia_auth_fetcher.cc(873)] Could not reach Google Accounts servers: errno -11
[32402:775:0416/102429.774193:WARNING:gaia_auth_fetcher.cc(873)] Could not reach Google Accounts servers: errno -11
[32402:775:0416/102432.463299:WARNING:gaia_auth_fetcher.cc(873)] Could not reach Google Accounts servers: errno -11
[32402:775:0416/102441.334886:WARNING:gaia_auth_fetcher.cc(873)] Could not reach Google Accounts servers: errno -11
[32410:775:0416/102455.019779:WARNING:vt_video_decode_accelerator_mac.cc(177)] Failed to create VTDecompressionSession: Error Domain=NSOSStatusErrorDomain Code=-8973 "codecOpenErr" (-8973)
[32410:775:0416/102455.020134:WARNING:vt_video_decode_accelerator_mac.cc(199)] Failed to create hardware VideoToolbox session
[32410:775:0416/102455.049542:ERROR:vt_video_encode_accelerator_mac.cc(517)]  VTCompressionSessionCreate failed: -12908
[32402:775:0416/102455.272776:WARNING:CONSOLE(0)] "Styling master document from stylesheets defined in HTML Imports is deprecated. Please refer to https://goo.gl/EGXzpw for possible migration paths.", source:  (0)
[32402:775:0416/102455.591083:INFO:media_browsertest.cc(32)] 10:24:55.591 Starting HTTP server
[32402:775:0416/102455.592891:INFO:media_browsertest.cc(48)] 10:24:55.592 Running test URL: http://127.0.0.1:64717/eme_player.html?mediaFile=bear-640x360-v_frag-cenc-mdat.mp4&mediaType=video/mp4;%20codecs=%22avc1.64001E%22&keySystem=org.w3.clearkey&useMSE=1
[32402:775:0416/102456.351716:INFO:CONSOLE(273)] "10:24:56.167 -  Using ClearKeyPlayer", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.352060:INFO:CONSOLE(273)] "10:24:56.169 -  Registering video event handlers.", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.352356:INFO:CONSOLE(273)] "10:24:56.171 -  Setting video media keys: org.w3.clearkey", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.361684:INFO:CONSOLE(273)] "10:24:56.275 -  Loading media using MSE.", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.362144:INFO:CONSOLE(273)] "10:24:56.278 -  video.src has been set to blob:http://127.0.0.1:64717/c66178e3-282e-439b-9820-21708a9072b9", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.362443:INFO:CONSOLE(273)] "10:24:56.279 -  initEMEPlayer() done", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.362728:INFO:CONSOLE(273)] "10:24:56.281 -  Media player created.", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.363010:INFO:CONSOLE(273)] "10:24:56.282 -  Starting play, hidden: false", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.365566:INFO:CONSOLE(273)] "10:24:56.298 -  Event: play", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.365927:INFO:CONSOLE(273)] "10:24:56.300 -  Event: waiting", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.369176:INFO:CONSOLE(273)] "10:24:56.305 -  onSourceOpen [object Event]", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.369634:INFO:CONSOLE(273)] "10:24:56.307 -  Creating a source buffer for type video/mp4; codecs="avc1.64001E"", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:89171:0416/102456.405729:WARNING:embedded_test_server.cc(228)] Request not handled. Returning 404: /favicon.ico
[32410:775:0416/102456.418577:ERROR:gpu_video_decode_accelerator.cc(369)] HW video decode not available for profile h264 high
[32591:33795:0416/102456.418935:ERROR:gpu_video_decode_accelerator_host.cc(99)] Send(GpuCommandBufferMsg_CreateVideoDecoder()) failed
[32410:42499:0416/102456.419165:ERROR:gpu_channel.cc(300)] Invalid route id
[32402:775:0416/102456.519745:INFO:CONSOLE(273)] "10:24:56.419 -  Creating new media key session for initDataType: cenc, initData: 000000347073736800000000edef8ba979d64acea3c827dcd51d21ed000000140801121030313233343536373839303132333435000002ea70737368000000009a04f07998404286ab92e65be0885f95000002caca02000001000100c0023c00570052004d00480045004100440045005200200078006d006c006e0073003d00220068007400740070003a002f002f0073006300680065006d00610073002e006d006900630072006f0073006f00660074002e0063006f006d002f00440052004d002f0032003000300037002f00300033002f0050006c00610079005200650061006400790048006500610064006500720022002000760065007200730069006f006e003d00220034002e0030002e0030002e00300022003e003c0044004100540041003e003c00500052004f00540045004300540049004e0046004f003e003c004b00450059004c0045004e003e00310036003c002f004b00450059004c0045004e003e003c0041004c004700490044003e004100450053004300540052003c002f0041004c004700490044003e003c002f00500052004f00540045004300540049004e0046004f003e003c004b00490044003e004d007a00490078004d004400550030004e007a00590034004f005400410078004d006a004d0030004e0051003d003d003c002f004b00490044003e003c0043004800450043004b00530055004d003e0044004d0039004c00590079006d003000470049006f003d003c002f0043004800450043004b00530055004d003e003c004c0041005f00550052004c003e00680074007400700073003a002f002f007700770077002e0079006f00750074007500620065002e0063006f006d002f006100700069002f00640072006d002f0070006c0061007900720065006100640079003f0073006f0075007200630065003d0059004f0055005400550042004500260061006d0070003b0076006900640065006f005f00690064003d00540065007300740056006900640065006f00490064003c002f004c0041005f00550052004c003e003c002f0044004100540041003e003c002f00570052004d004800450041004400450052003e000000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000013031323334353637383930313233343500000000", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.520149:INFO:CONSOLE(273)] "10:24:56.427 -  MediaKeyMessageEvent: license-request", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.520444:INFO:CONSOLE(273)] "10:24:56.432 -  Calling update: {"keys":[{"kty":"oct","alg":"A128KW","kid":"MDEyMzQ1Njc4OTAxMjM0NQ","k":"691i8WgU0nto7xIq_OSuPA"}]}", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.520732:INFO:CONSOLE(273)] "10:24:56.435 -  End of appending buffer from bear-640x360-v_frag-cenc-mdat.mp4", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.521927:INFO:CONSOLE(273)] "10:24:56.457 -  Event: canplay", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.522216:INFO:CONSOLE(273)] "10:24:56.461 -  Event: playing", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.522510:INFO:CONSOLE(273)] "10:24:56.463 -  Event: canplaythrough", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.962059:INFO:CONSOLE(273)] "10:24:56.719 -  timeupdate @ 0.262017", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.965491:INFO:CONSOLE(273)] "10:24:56.962 -  timeupdate @ 0.50462", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.985892:INFO:CONSOLE(273)] "10:24:56.983 -  timeupdate @ 0.516931", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.987528:INFO:CONSOLE(273)] "10:24:56.986 -  Event: waiting", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.991272:INFO:CONSOLE(273)] "10:24:56.988 -  Event: canplay", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.993208:INFO:CONSOLE(273)] "10:24:56.990 -  Event: playing", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102456.995266:INFO:CONSOLE(273)] "10:24:56.993 -  Event: canplaythrough", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102457.273201:INFO:CONSOLE(273)] "10:24:57.175 -  heartbeat #1", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102457.273515:INFO:CONSOLE(273)] "10:24:57.230 -  timeupdate @ 0.761562", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102457.579848:INFO:CONSOLE(273)] "10:24:57.479 -  timeupdate @ 1.01105", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
[32402:775:0416/102457.580338:INFO:CONSOLE(273)] "10:24:57.484 -  waiting for video to end.", source: http://127.0.0.1:64717/eme_player_js/utils.js (273)
BrowserTestBase received signal: Terminated: 15. Backtrace:
0   browser_tests                       0x0000000108917b1c base::debug::StackTrace::StackTrace(unsigned long) + 28
1   browser_tests                       0x00000001091d9a55 content::(anonymous namespace)::DumpStackTraceSignalHandler(int) + 229
2   libsystem_platform.dylib            0x00007fff7b7cdf5a _sigtramp + 26
3   ???                                 0x000000011f639000 0x0 + 4821585920
4   browser_tests                       0x00000001089bbea7 base::WaitableEvent::TimedWaitUntil(base::TimeTicks const&) + 359
5   browser_tests                       0x00000001089bbcd9 base::WaitableEvent::Wait() + 41
6   browser_tests                       0x00000001063948c4 gpu::GpuChannelHost::Send(IPC::Message*) + 980
7   browser_tests                       0x000000010638fbd6 gpu::CommandBufferProxyImpl::Send(IPC::Message*) + 422
8   browser_tests                       0x00000001063902dc gpu::CommandBufferProxyImpl::WaitForGetOffsetInRange(unsigned int, int, int) + 428
9   browser_tests                       0x000000010600a91a gpu::CommandBufferHelper::WaitForGetOffsetInRange(int, int) + 202
10  browser_tests                       0x000000010600ac58 gpu::CommandBufferHelper::Finish() + 104
11  browser_tests                       0x000000010a998837 gpu::ImplementationBase::WaitForCmd() + 55
12  browser_tests                       0x000000010a9626b2 gpu::gles2::GLES2Implementation::CheckFramebufferStatus(unsigned int) + 546
13  browser_tests                       0x000000010aee8e89 viz::GLRenderer::CopyRenderPassDrawQuadToOverlayResource(viz::CALayerOverlay const*, std::__1::unique_ptr<viz::GLRenderer::OverlayTexture, std::__1::default_delete<viz::GLRenderer::OverlayTexture> >*, gfx::RectF*) + 2857
14  browser_tests                       0x000000010aee7cd0 viz::GLRenderer::ScheduleRenderPassDrawQuad(viz::CALayerOverlay const*) + 160
15  browser_tests                       0x000000010aee4986 viz::GLRenderer::ScheduleCALayers() + 246
16  browser_tests                       0x000000010aee3edf viz::GLRenderer::FinishDrawingFrame() + 335
17  browser_tests                       0x000000010aec87d9 viz::DirectRenderer::DrawFrame(std::__1::vector<std::__1::unique_ptr<viz::RenderPass, std::__1::default_delete<viz::RenderPass> >, std::__1::allocator<std::__1::unique_ptr<viz::RenderPass, std::__1::default_delete<viz::RenderPass> > > >*, float, gfx::Size const&) + 3689
18  browser_tests                       0x000000010aece6c3 viz::Display::DrawAndSwap() + 1763
19  browser_tests                       0x000000010aed2c7c viz::DisplayScheduler::DrawAndSwap() + 236
20  browser_tests                       0x000000010aed22df viz::DisplayScheduler::AttemptDrawAndSwap() + 223
21  browser_tests                       0x000000010aed1b38 viz::DisplayScheduler::OnBeginFrameDeadline() + 136
22  browser_tests                       0x000000010aed4767 base::internal::Invoker<base::internal::BindState<void (viz::DisplayScheduler::*)(), base::WeakPtr<viz::DisplayScheduler> >, void ()>::Run(base::internal::BindStateBase*) + 183
23  browser_tests                       0x00000001043b9c9f void base::internal::CancelableCallbackImpl<base::RepeatingCallback<void ()> >::ForwardRepeating<>() + 95
24  browser_tests                       0x00000001043b9e07 base::internal::Invoker<base::internal::BindState<void (base::internal::CancelableCallbackImpl<base::RepeatingCallback<void ()> >::*)(), base::WeakPtr<base::internal::CancelableCallbackImpl<base::RepeatingCallback<void ()> > > >, void ()>::Run(base::internal::BindStateBase*) + 183
25  browser_tests                       0x00000001071ac42c base::OnceCallback<void ()>::Run() && + 108
26  browser_tests                       0x0000000108918553 base::debug::TaskAnnotator::RunTask(char const*, base::PendingTask*) + 323
27  browser_tests                       0x0000000108954779 base::internal::IncomingTaskQueue::RunTask(base::PendingTask*) + 121
28  browser_tests                       0x0000000108958487 base::MessageLoop::RunTask(base::PendingTask*) + 599
29  browser_tests                       0x000000010895884a base::MessageLoop::DeferOrRunPendingTask(base::PendingTask) + 186
30  browser_tests                       0x0000000108958abc base::MessageLoop::DoWork() + 572
31  browser_tests                       0x000000010895d50a base::MessagePumpCFRunLoopBase::RunWork() + 42
32  browser_tests                       0x000000010893e70a base::mac::CallWithEHFrame(void () block_pointer) + 10
33  browser_tests                       0x000000010895cdef base::MessagePumpCFRunLoopBase::RunWorkSource(void*) + 63
34  CoreFoundation                      0x00007fff53bb8a21 __CFRUNLOOP_IS_CALLING_OUT_TO_A_SOURCE0_PERFORM_FUNCTION__ + 17
35  CoreFoundation                      0x00007fff53c7026c __CFRunLoopDoSource0 + 108
36  CoreFoundation                      0x00007fff53b9bab0 __CFRunLoopDoSources0 + 208
37  CoreFoundation                      0x00007fff53b9af2d __CFRunLoopRun + 1293
38  CoreFoundation                      0x00007fff53b9a787 CFRunLoopRunSpecific + 487
39  HIToolbox                           0x00007fff52ea7e26 RunCurrentEventLoopInMode + 286
40  HIToolbox                           0x00007fff52ea7b96 ReceiveNextEventCommon + 613
41  HIToolbox                           0x00007fff52ea7914 _BlockUntilNextEventMatchingListInModeWithFilter + 64
42  AppKit                              0x00007fff51172f5f _DPSNextEvent + 2085
43  AppKit                              0x00007fff51908b4c -[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 3044
44  browser_tests                       0x0000000108b17de0 __71-[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:]_block_invoke + 64
45  browser_tests                       0x000000010893e70a base::mac::CallWithEHFrame(void () block_pointer) + 10
46  browser_tests                       0x0000000108b17d24 -[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:] + 164
47  AppKit                              0x00007fff51167d6d -[NSApplication run] + 764
48  browser_tests                       0x000000010895e5cc base::MessagePumpNSApplication::DoRun(base::MessagePump::Delegate*) + 364
49  browser_tests                       0x000000010895c83e base::MessagePumpCFRunLoopBase::Run(base::MessagePump::Delegate*) + 110
50  browser_tests                       0x0000000108957e64 base::MessageLoop::Run(bool) + 132
51  browser_tests                       0x0000000108996da9 base::RunLoop::Run() + 249
52  browser_tests                       0x00000001091e1ad7 content::TitleWatcher::WaitAndGetTitle() + 23
53  browser_tests                       0x00000001044ff095 MediaBrowserTest::RunTest(GURL const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&) + 421
54  browser_tests                       0x00000001044fed81 MediaBrowserTest::RunMediaTestPage(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::vector<std::__1::pair<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > >, std::__1::allocator<std::__1::pair<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > > > > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, bool) + 689
55  browser_tests                       0x00000001044de852 EncryptedMediaTestBase::RunEncryptedMediaTestPage(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::vector<std::__1::pair<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > >, std::__1::allocator<std::__1::pair<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > > > > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&) + 226
56  browser_tests                       0x00000001044d1d96 EncryptedMediaTestBase::RunEncryptedMediaTest(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, SrcType, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, bool, PlayCount, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&) + 822
57  browser_tests                       0x00000001044d4aae EncryptedMediaTestBase::RunSimpleEncryptedMediaTest(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, SrcType) + 318
58  browser_tests                       0x00000001044ce915 EncryptedMediaTest::TestSimplePlayback(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&) + 213
59  browser_tests                       0x00000001044d22a8 EncryptedMediaTest_Playback_VideoOnly_MP4_MDAT_Test::RunTestOnMainThread() + 232
60  browser_tests                       0x00000001091d9729 content::BrowserTestBase::ProxyRunTestOnMainThreadLoop() + 649
61  browser_tests                       0x0000000108b1e610 ChromeBrowserMainParts::PreMainMessageLoopRunImpl() + 4768


Comment 3 by awdf@chromium.org, Apr 16 2018

Labels: Sheriff-Chromium
Cc: -tommi@chromium.org jrumm...@chromium.org ommi@chromium.org xhw...@chromium.org
+xhwang, jrummell since there are quite a few EME ones in there.
Cc: -ommi@chromium.org tommi@chromium.org

Comment 6 by xhw...@chromium.org, Apr 16 2018

Given some unrelated tests are timing out together. Is it possible that the bot is just slow in some cases?
Labels: -Sheriff-Chromium
Blockedon: 835585
More timeouts:
https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/45902
[ RUN      ] MediaEngagementBrowserTest.RecordSingleVisitOnSameOrigin
[60589:4355:0514/135743.249697:WARNING:notification_platform_bridge_mac.mm(510)] AlertNotificationService: XPC connection invalidated.
2018-05-14 13:57:43.390 browser_tests[60589:2545846] *** Owner supplied to -[NSTrackingArea initWithRect:options:owner:userInfo:] referenced a deallocating object. Tracking area behavior is undefined. Break on NSTrackingAreaDeallocatingOwnerError to debug.
[60589:775:0514/135743.773978:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[60589:775:0514/135744.693377:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[60589:775:0514/135747.571193:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[60589:775:0514/135756.491497:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
BrowserTestBase received signal: Terminated: 15. Backtrace:
0   browser_tests                       0x000000010eff964c base::debug::StackTrace::StackTrace(unsigned long) + 28
1   browser_tests                       0x000000010f7afbe5 content::(anonymous namespace)::DumpStackTraceSignalHandler(int) + 229
2   libsystem_platform.dylib            0x00007fff7567cf5a _sigtramp + 26
3   browser_tests                       0x000000010eeeb74b base::internal::CallbackBase::IsCancelled() const + 171
4   CoreFoundation                      0x00007fff4da4adc5 __CFRunLoopServiceMachPort + 341
5   CoreFoundation                      0x00007fff4da4a117 __CFRunLoopRun + 1783
6   CoreFoundation                      0x00007fff4da49787 CFRunLoopRunSpecific + 487
7   HIToolbox                           0x00007fff4cd56e26 RunCurrentEventLoopInMode + 286
8   HIToolbox                           0x00007fff4cd56b96 ReceiveNextEventCommon + 613
9   HIToolbox                           0x00007fff4cd56914 _BlockUntilNextEventMatchingListInModeWithFilter + 64
10  AppKit                              0x00007fff4b021f5f _DPSNextEvent + 2085
11  AppKit                              0x00007fff4b7b7b4c -[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 3044
12  browser_tests                       0x000000010f0f87c0 __71-[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:]_block_invoke + 64
13  browser_tests                       0x000000010ef15d7a base::mac::CallWithEHFrame(void () block_pointer) + 10
14  browser_tests                       0x000000010f0f86f4 -[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:] + 164
15  AppKit                              0x00007fff4b016d6d -[NSApplication run] + 764
16  browser_tests                       0x000000010ef34e5c base::MessagePumpNSApplication::DoRun(base::MessagePump::Delegate*) + 364
17  browser_tests                       0x000000010ef330ae base::MessagePumpCFRunLoopBase::Run(base::MessagePump::Delegate*) + 110
18  browser_tests                       0x000000010ef2f784 base::MessageLoop::Run(bool) + 132
19  browser_tests                       0x000000010ef6a4e9 base::RunLoop::Run() + 249
20  browser_tests                       0x000000010f7eca66 content::MessageLoopRunner::Run() + 134
21  browser_tests                       0x000000010f7e97e9 content::TestNavigationObserver::Wait() + 105
22  browser_tests                       0x000000010f056881 ui_test_utils::NavigateToURLWithDispositionBlockUntilNavigationsComplete(Browser*, GURL const&, int, WindowOpenDisposition, int) + 1489
23  browser_tests                       0x000000010a9c0913 MediaEngagementBrowserTest::LoadTestPageAndWaitForPlayAndAudible(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, bool) + 99
24  browser_tests                       0x000000010a9c21fe MediaEngagementBrowserTest_RecordSingleVisitOnSameOrigin_Test::RunTestOnMainThread() + 142
25  browser_tests                       0x000000010f7af8bf content::BrowserTestBase::ProxyRunTestOnMainThreadLoop() + 591
26  browser_tests                       0x000000010f0fef03 ChromeBrowserMainParts::PreMainMessageLoopRunImpl() + 4643
27  browser_tests                       0x000000010f0fdbde ChromeBrowserMainParts::PreMainMessageLoopRun() + 62
28  browser_tests                       0x000000010cefd063 content::BrowserMainLoop::PreMainMessageLoopRun() + 67
29  browser_tests                       0x000000010d44d985 content::StartupTaskRunner::RunAllTasksNow() + 117
30  browser_tests                       0x000000010cefbb22 content::BrowserMainLoop::CreateStartupTasks() + 914
31  browser_tests                       0x000000010ceffa65 content::BrowserMainRunnerImpl::Initialize(content::MainFunctionParams const&, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 101
32  browser_tests                       0x000000010cef9955 content::BrowserMain(content::MainFunctionParams const&, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 229
33  browser_tests                       0x000000010ed83513 content::RunBrowserProcessMain(content::MainFunctionParams const&, content::ContentMainDelegate*, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 195
34  browser_tests                       0x000000010ed8461f content::ContentMainRunnerImpl::Run() + 687
35  browser_tests                       0x000000011167fdd6 service_manager::Main(service_manager::MainParams const&) + 2854
36  browser_tests                       0x000000010ed83424 content::ContentMain(content::ContentMainParams const&) + 68
37  browser_tests                       0x000000010f7af43f content::BrowserTestBase::SetUp() + 3215
38  browser_tests                       0x000000010f052a75 InProcessBrowserTest::SetUp() + 613
39  browser_tests                       0x000000010a9c5c6f MediaEngagementBrowserTest::SetUp() + 95
40  browser_tests                       0x000000010bd3a62d testing::Test::Run() + 109
41  browser_tests                       0x000000010bd3b2f0 testing::TestInfo::Run() + 304
42  browser_tests                       0x000000010bd3b927 testing::TestCase::Run() + 279
43  browser_tests                       0x000000010bd480d7 testing::internal::UnitTestImpl::RunAllTests() + 855
44  browser_tests                       0x000000010bd47d5e testing::UnitTest::Run() + 110
45  browser_tests                       0x000000010f074f37 base::TestSuite::Run() + 167
46  browser_tests                       0x000000010eede3c5 ChromeTestSuiteRunner::RunTestSuite(int, char**) + 37
47  browser_tests                       0x000000010f7e65c6 content::LaunchTests(content::TestLauncherDelegate*, unsigned long, int, char**) + 470
48  browser_tests                       0x000000010eede8dc LaunchChromeTests(unsigned long, content::TestLauncherDelegate*, int, char**) + 348
49  browser_tests                       0x000000010eede31e main + 94
50  libdyld.dylib                       0x00007fff753fb115 start + 1
[109/881] MediaEngagementBrowserTest.RecordSingleVisitOnSameOrigin (TIMED OUT)

[ RUN      ] AutomationApiTest.Events
[62563:4611:0514/140034.504277:WARNING:notification_platform_bridge_mac.mm(510)] AlertNotificationService: XPC connection invalidated.
2018-05-14 14:00:34.658 browser_tests[62563:2576121] *** Owner supplied to -[NSTrackingArea initWithRect:options:owner:userInfo:] referenced a deallocating object. Tracking area behavior is undefined. Break on NSTrackingAreaDeallocatingOwnerError to debug.
[62571:775:0514/140034.659692:WARNING:vt_video_decode_accelerator_mac.cc(178)] Failed to create VTDecompressionSession: Error Domain=NSOSStatusErrorDomain Code=-8973 "codecOpenErr" (-8973)
[62571:775:0514/140034.660230:WARNING:vt_video_decode_accelerator_mac.cc(200)] Hardware decoding with VideoToolbox is not supported
[62571:775:0514/140034.696782:ERROR:vt_video_encode_accelerator_mac.cc(516)]  VTCompressionSessionCreate failed: -12908
[62563:775:0514/140035.176523:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[62563:775:0514/140036.139753:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -102
[62563:775:0514/140039.129708:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -102
[62563:775:0514/140046.690519:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -102
BrowserTestBase received signal: Terminated: 15. Backtrace:
0   browser_tests                       0x0000000104c3064c base::debug::StackTrace::StackTrace(unsigned long) + 28
1   browser_tests                       0x00000001053e6be5 content::(anonymous namespace)::DumpStackTraceSignalHandler(int) + 229
2   libsystem_platform.dylib            0x00007fff7567cf5a _sigtramp + 26
3   browser_tests                       0x0000000104b2274b base::internal::CallbackBase::IsCancelled() const + 171
4   CoreFoundation                      0x00007fff4da4adc5 __CFRunLoopServiceMachPort + 341
5   CoreFoundation                      0x00007fff4da4a117 __CFRunLoopRun + 1783
6   CoreFoundation                      0x00007fff4da49787 CFRunLoopRunSpecific + 487
7   HIToolbox                           0x00007fff4cd56e26 RunCurrentEventLoopInMode + 286
8   HIToolbox                           0x00007fff4cd56b96 ReceiveNextEventCommon + 613
9   HIToolbox                           0x00007fff4cd56914 _BlockUntilNextEventMatchingListInModeWithFilter + 64
10  AppKit                              0x00007fff4b021f5f _DPSNextEvent + 2085
11  AppKit                              0x00007fff4b7b7b4c -[NSApplication(NSEvent) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 3044
12  browser_tests                       0x0000000104d2f7c0 __71-[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:]_block_invoke + 64
13  browser_tests                       0x0000000104b4cd7a base::mac::CallWithEHFrame(void () block_pointer) + 10
14  browser_tests                       0x0000000104d2f6f4 -[BrowserCrApplication nextEventMatchingMask:untilDate:inMode:dequeue:] + 164
15  AppKit                              0x00007fff4b016d6d -[NSApplication run] + 764
16  browser_tests                       0x0000000104b6be5c base::MessagePumpNSApplication::DoRun(base::MessagePump::Delegate*) + 364
17  browser_tests                       0x0000000104b6a0ae base::MessagePumpCFRunLoopBase::Run(base::MessagePump::Delegate*) + 110
18  browser_tests                       0x0000000104b66784 base::MessageLoop::Run(bool) + 132
19  browser_tests                       0x0000000104ba14e9 base::RunLoop::Run() + 249
20  browser_tests                       0x0000000105422892 content::RunThisRunLoop(base::RunLoop*) + 34
21  browser_tests                       0x000000010b5f50ff extensions::ResultCatcher::GetNextResult() + 127
22  browser_tests                       0x000000010125a682 ExtensionApiTest::RunExtensionTestImpl(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, char const*, int) + 978
23  browser_tests                       0x000000010125aa27 ExtensionApiTest::RunExtensionSubtestWithArgAndFlags(std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&, char const*, int) + 167
24  browser_tests                       0x0000000100f46079 extensions::AutomationApiTest_Events_Test::RunTestOnMainThread() + 137
25  browser_tests                       0x00000001053e68bf content::BrowserTestBase::ProxyRunTestOnMainThreadLoop() + 591
26  browser_tests                       0x0000000104d35f03 ChromeBrowserMainParts::PreMainMessageLoopRunImpl() + 4643
27  browser_tests                       0x0000000104d34bde ChromeBrowserMainParts::PreMainMessageLoopRun() + 62
28  browser_tests                       0x0000000102b34063 content::BrowserMainLoop::PreMainMessageLoopRun() + 67
29  browser_tests                       0x0000000103084985 content::StartupTaskRunner::RunAllTasksNow() + 117
30  browser_tests                       0x0000000102b32b22 content::BrowserMainLoop::CreateStartupTasks() + 914
31  browser_tests                       0x0000000102b36a65 content::BrowserMainRunnerImpl::Initialize(content::MainFunctionParams const&, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 101
32  browser_tests                       0x0000000102b30955 content::BrowserMain(content::MainFunctionParams const&, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 229
33  browser_tests                       0x00000001049ba513 content::RunBrowserProcessMain(content::MainFunctionParams const&, content::ContentMainDelegate*, std::__1::unique_ptr<content::BrowserProcessSubThread, std::__1::default_delete<content::BrowserProcessSubThread> >) + 195
34  browser_tests                       0x00000001049bb61f content::ContentMainRunnerImpl::Run() + 687
35  browser_tests                       0x00000001072b6dd6 service_manager::Main(service_manager::MainParams const&) + 2854
36  browser_tests                       0x00000001049ba424 content::ContentMain(content::ContentMainParams const&) + 68
37  browser_tests                       0x00000001053e643f content::BrowserTestBase::SetUp() + 3215
38  browser_tests                       0x0000000104c89a75 InProcessBrowserTest::SetUp() + 613
39  browser_tests                       0x000000010197162d testing::Test::Run() + 109
40  browser_tests                       0x00000001019722f0 testing::TestInfo::Run() + 304
41  browser_tests                       0x0000000101972927 testing::TestCase::Run() + 279
42  browser_tests                       0x000000010197f0d7 testing::internal::UnitTestImpl::RunAllTests() + 855
43  browser_tests                       0x000000010197ed5e testing::UnitTest::Run() + 110
44  browser_tests                       0x0000000104cabf37 base::TestSuite::Run() + 167
45  browser_tests                       0x0000000104b153c5 ChromeTestSuiteRunner::RunTestSuite(int, char**) + 37
46  browser_tests                       0x000000010541d5c6 content::LaunchTests(content::TestLauncherDelegate*, unsigned long, int, char**) + 470
47  browser_tests                       0x0000000104b158dc LaunchChromeTests(unsigned long, content::TestLauncherDelegate*, int, char**) + 348
48  browser_tests                       0x0000000104b1531e main + 94
49  libdyld.dylib                       0x00007fff753fb115 start + 1
[385/881] AutomationApiTest.Events (TIMED OUT)

and 17 more.
Cc: mar...@chromium.org dpranke@chromium.org
 Issue 835585  has been merged into this issue.
It seems to me this mac bot is just slower than others. To fix the issue, does it make sense to bump the timeout limit for browser_tests on this mac bot?
Status: Available (was: Untriaged)
Xiaohan, there are multiple time outs:
- There's a Swarming task timeout. Taking https://chromium-swarm.appspot.com/task?id=3d79414d0f523310 as an example, the timeout is 1 hour (you have to click on "More Details") and the task ran for 16.5 minutes. That's not the problem.
- There's a Unit test timeout. It's inside the test framework; https://cs.chromium.org/chromium/src/base/test/launcher/test_launcher.cc?l=720

The first one is generally corrected by increasing the sharding level, i.e. runs less test cases per Swarming task, by spreading the test cases over more shards.

The second one is a structural issue in the test case. You need to fix the test case. This is what happens here.
Thanks! For the second case, how do I find out the timeout set for each test suite (e.g. browser_tests), or test case (e.g. EncryptedMediaTest)? I searched around and didn't see anything obvious.
Clicking around led to https://cs.chromium.org/chromium/src/base/test/test_timeouts.cc?g=0&l=71

Do not change these values. Make sure the test case completes in time.
Thanks! I assumed there's finer control on timeout for each test suite on each bot. Apparently I was wrong.

But for this bot, since multiple tests are timing out, it seems the bot is slower than others. I don't feel we should fix/modify each individual test to make they finish in time on that bot. What if in the future we add a slow (e.g. low end device) bot for testing on purpose? Does it make sense to have a way to adjust the kTimeoutMultiplier [1] for slower bots?

[1] https://cs.chromium.org/chromium/src/base/test/test_timeouts.cc?rcl=e0a5855513286b1318bebf5540e5c3db0633e1b8&l=41
What makes you say that this builder is slower than the others? What are you comparing things to?
Looks like the timeout that is applied in this case is "TestTimeouts::action_max_timeout_ms_ = 30000"?

Comparing passing
https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/45901
with failing
https://ci.chromium.org/p/chromium/builders/luci.chromium.try/mac_chromium_rel_ng/45902.

Failing has [109/881] MediaEngagementBrowserTest.RecordSingleVisitOnSameOrigin (TIMED OUT)
Passing has [111/881] MediaEngagementBrowserTest.RecordSingleVisitOnSameOrigin (9007 ms)

9007 ms is well within 30000 ms.

Sounds like the right fix is to make sure that the test doesn't deviate too much from 9007 ms.
This message during the timeout makes me suspicious:

2018-05-14 13:57:43.390 browser_tests[60589:2545846] *** Owner supplied to -[NSTrackingArea initWithRect:options:owner:userInfo:] referenced a deallocating object. Tracking area behavior is undefined. Break on NSTrackingAreaDeallocatingOwnerError to debug.

Re #16: I don't actually have any hard proof and this was just my feeling by looking at some of the test results. For example, even when MSE_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 passes, it can take as long as 17 seconds to finish [1]. But if 30s is the actual timeout, I agree we are still far from that limit and there might be something else going on preventing the test from finishing.

[1]
[690/873] MSE_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0 (17514 ms)

https://logs.chromium.org/v/?s=chromium%2Fbuildbucket%2Fcr-buildbucket.appspot.com%2F8947534405424895520%2F%2B%2Fsteps%2Fbrowser_tests__with_patch_%2F0%2Fstdout

Cc: -asargent@chromium.org
Project Member

Comment 21 by chromium...@appspot.gserviceaccount.com, May 31 2018

Labels: Sheriff-Chromium
Detected 3 new flakes for test/step "MSE_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0". To see the actual flakes, please visit https://chromium-try-flakes.appspot.com/all_flake_occurrences?key=ahVzfmNocm9taXVtLXRyeS1mbGFrZXNyVgsSBUZsYWtlIktNU0VfRXh0ZXJuYWxDbGVhcktleS9FbmNyeXB0ZWRNZWRpYVRlc3QuUGxheWJhY2tfTXVsdGlwbGVfVmlkZW9BdWRpb19XZWJNLzAM. This message was posted automatically by the chromium-try-flakes app. Since flakiness is ongoing, the issue was moved back into Sheriff Bug Queue (unless already there).
Looking at the results mentioned in #21, the "Playback_Multiple_VideoAudio_WebM" tests are already disabled for Mac from  issue 835585  (on May 3, so no idea why the chromium-try-flakes app is just reporting it now).

However, it appears to me that something at startup is taking a long time. Looking at the log from the last Mac run:
[ RUN      ] MSE_ExternalClearKey/EncryptedMediaTest.Playback_Multiple_VideoAudio_WebM/0
[38746:19207:0531/100724.156070:WARNING:notification_platform_bridge_mac.mm(510)] AlertNotificationService: XPC connection invalidated.
2018-05-31 10:07:24.255 browser_tests[38746:685583] *** Owner supplied to -[NSTrackingArea initWithRect:options:owner:userInfo:] referenced a deallocating object. Tracking area behavior is undefined. Break on NSTrackingAreaDeallocatingOwnerError to debug.
[38746:775:0531/100724.534901:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[38746:775:0531/100725.407673:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[38746:775:0531/100728.180495:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[38746:775:0531/100736.355941:WARNING:gaia_auth_fetcher.cc(902)] Could not reach Google Accounts servers: errno -11
[38751:775:0531/100747.849867:WARNING:vt_video_decode_accelerator_mac.cc(193)] Failed to create VTDecompressionSession: Error Domain=NSOSStatusErrorDomain Code=-8973 "codecOpenErr" (-8973)
[38751:775:0531/100747.850221:WARNING:vt_video_decode_accelerator_mac.cc(215)] Hardware decoding with VideoToolbox is not supported
[38751:775:0531/100747.873163:ERROR:vt_video_encode_accelerator_mac.cc(516)]  VTCompressionSessionCreate failed: -12908
[38746:775:0531/100748.094148:WARNING:CONSOLE(0)] "Styling master document from stylesheets defined in HTML Imports is deprecated. Please refer to https://goo.gl/EGXzpw for possible migration paths.", source:  (0)
[38746:775:0531/100748.352711:INFO:media_browsertest.cc(32)] 10:07:48.352 Starting HTTP server
[38746:775:0531/100748.354615:INFO:media_browsertest.cc(48)] 10:07:48.354 Running test URL: http://127.0.0.1:57320/eme_player.html?mediaFile=bear-320x240-av_enc-av.webm&mediaType=video/webm;%20codecs=%22vorbis,%20vp8%22&keySystem=org.chromium.externalclearkey&useMSE=1&playTwice=1

So basically initialization takes 24 seconds (from 10:07:24 until 10:07:48) to start running the code for the test. If the timeout is 30 seconds, this test only really has 6 seconds to run, which is clearly not enough. (The previous build running this test took 22764 ms, although without detailed logs I can't tell how much of that time was after "Starting HTTP server", which is when the actual test starts running).
Status: WontFix (was: Available)
This bug is quite old and the last 1000 builds on that bot aren't showing browser_test flakiness from timeouts. Going to close this, if someone thinks we should keep looking please reopen.
 Issue 851085  has been merged into this issue.
xhwang: can you please disable the test
MSE_ExternalClearKey/EncryptedMediaTest.Playback_VP9Video_WebM_Subsample/0
in question or perhaps the whole suite on Mac?

I would like to remove this from the sheriff queue.
I can. But if you look at #1, there are a lot other non-EncryptedMediaTest tests timing out on Mac. So I don't really feel disabling EncryptedMediaTest is the right solution. EncryptedMediaTest shows up often because we need to play actual video and it's typically slower than other tests. 

Maybe we should have a Mac gardener looking into the bot?
Comment #1 is no longer applicable.  (Maybe most of those were fixed?)

From the merged-in bug, the three most recent flakes of MSE_ExternalClearKey/EncryptedMediaTest.Playback_VP9Video_WebM_Subsample/0
were standalone failures.  Nothing else failed at the same time.

Note that comment #1 is about all the timeouts. Some of these tests pass on
2nd or 3rd retry.
I agree our test is probably more flakier than others, because the test itself takes more time. But that doesn't mean other tests are not flaky. For example, MediaEngagementBrowserTest.* reported in #1 still timeouts often:

https://test-results.appspot.com/dashboards/flakiness_dashboard.html#testType=browser_tests%20(with%20patch)&tests=MediaEngagementBrowserTest.*

Or, you can see how flaky browser_test as a whole is here:

https://test-results.appspot.com/dashboards/flakiness_dashboard.html#testType=browser_tests%20(with%20patch)&builder=tryserver.chromium.mac%3Amac_chromium_rel_ng
Note that in the first link [1] in #30, you can see that most of the timeouts (marked as "30") happens on mac_chromium_rel_ng, not on other bots, not even on mac_chromium_dbg_ng.

[1] https://test-results.appspot.com/dashboards/flakiness_dashboard.html#testType=browser_tests%20(with%20patch)&tests=MediaEngagementBrowserTest.*
Okay, I'm convinced that MSE_ExternalClearKey/EncryptedMediaTest.Playback_VP9Video_WebM_Subsample/0 doesn't flake / fail any more frequently than any of the other browser tests.

Indeed, that flakiness dashboard doesn't look bad at all, except for
BackgroundFetchBrowserTest.OfflineItemCollection_VerifyResourceDownloadedWhenDownloadTotalSmallerThanActualSize
and
ExtensionInstallDialogViewTest.NotifyDelegate
(which I fill follow up with separately)

I'm inclined to close this bug as WontFix, as it's not actionable in its current form.
Well, I think there's still an issue with mac_chromium_rel_ng, it seems test are more likely to timeout on this specific bot. See #22, it doesn't make sense for some test initialization to take 20+ seconds. If that's expected, we should update how we calculate timeout to account for it. Otherwise, there'll be bugs filed everyday and merged into this issue...
Labels: -Pri-1 Pri-2
Okay, keeping within sheriff queue and demoting.  Hopefully someone will get to the bottom of this sometime...
I'm going to remove this from the sheriff queue, actually. 

We don't have a good mechanism for distinguishing between "this is a new thing that a sheriff needs to deal with" and "here's something a sheriff might want to be aware of", but I think we normally try to keep the Sheriff-Chromium label restricted to the former category, and I think this bug largely falls into the latter category.

Comment 36 by treib@chromium.org, Jun 18 2018

Labels: -Sheriff-Chromium
Actually removing from sheriff queue per #35

Sign in to add a comment