hackernews in telemetry_perf_unittests fails when touch_event_api flag is enabled |
||||||
Issue descriptionIn telemetry_perf_unittest, benchmarks.system_health_smoke_test.SystemHealthBenchmarkSmokeTest.system_health.memory_desktop.browse:news:hackernews fails when touch_event_api flag is enabled. Version: 56.0.2909.0 OS: Linux, Windows What is the expected result? The test should pass when the touch_event_api flag is enabled(default value) What happens instead? The test fails when the nintendo webpage is loading.
,
Nov 15 2016
Do you have the browser log/crash stack when the test fails?
,
Nov 15 2016
Here is one instance of failure, has stack trace: https://chromium-swarm.appspot.com/task?id=325f832a5d486510&refresh=10&show_raw=1 When the test is run locally, the test browser stall after the Nintendo page is rendered.
,
Nov 18 2016
Note that the stack trace is exactly the same as in Issue 657665 (for Windows), so these are effectively the same issue. In other words, the change of touch flag in this bug simply exposed the same old telemetry bug in linux.
,
Nov 18 2016
Here is the stack trace from the log in #3 above: benchmarks.system_health_smoke_test.SystemHealthBenchmarkSmokeTest.system_health.memory_desktop.browse:news:hackernews failed unexpectedly 156.3010s: [ RUN ] browse:news:hackernews Traceback (most recent call last): File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/telemetry/telemetry/internal/story_runner.py", line 87, in _RunStoryAndProcessErrorIfNeeded state.RunStory(results) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function return func(*args, **kwargs) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/telemetry/telemetry/page/shared_page_state.py", line 299, in RunStory self._current_page.Run(self) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/telemetry/telemetry/page/__init__.py", line 109, in Run self.RunPageInteractions(action_runner) File "/b/swarm_slave/w/irOUEzaN/tools/perf/page_sets/system_health/system_health_story.py", line 112, in RunPageInteractions self._DidLoadDocument(action_runner) File "/b/swarm_slave/w/irOUEzaN/tools/perf/page_sets/system_health/browsing_stories.py", line 72, in _DidLoadDocument self._ReadNewsItem(action_runner) File "/b/swarm_slave/w/irOUEzaN/tools/perf/page_sets/system_health/browsing_stories.py", line 77, in _ReadNewsItem action_runner.tab.WaitForDocumentReadyStateToBeComplete() File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function return func(*args, **kwargs) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 76, in WaitForDocumentReadyStateToBeComplete 'document.readyState == "complete"', timeout) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function return func(*args, **kwargs) File "/b/swarm_slave/w/irOUEzaN/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 142, in WaitForJavaScriptExpression e.message + '\n' + debug_message) TimeoutException: Timed out while waiting 90s for IsJavaScriptExpressionTrue. Console output:
,
Nov 22 2016
The stack worth taking a look at is the browser STDOUT & browser LOG: *************** BROWSER STANDARD OUTPUT *************** [1:1:1108/173016:ERROR:memory_mapped_file.cc(52)] Couldn't open /b/swarm_slave/w/irOUEzaN/out/Release/chrome_200_percent.pak [1:1:1108/173016:ERROR:data_pack.cc(79)] Failed to mmap datapack Xlib: extension "RANDR" missing on display ":9". [2575:2575:1108/173016:ERROR:memory_mapped_file.cc(52)] Couldn't open /b/swarm_slave/w/irOUEzaN/out/Release/chrome_200_percent.pak [2575:2575:1108/173016:ERROR:data_pack.cc(79)] Failed to mmap datapack Xlib: extension "RANDR" missing on display ":9". [2575:2590:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for chromium-i18n.appspot.com failed err=-8172 [2618:2618:1108/173017:ERROR:sandbox_linux.cc(343)] InitializeSandbox() called with multiple threads in process gpu-process. [2575:2598:1108/173017:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel. [2618:2638:1108/173017:FATAL:gpu_channel.cc(460)] Check failed: channel_. #0 0x7f062c8e2f2e base::debug::StackTrace::StackTrace() #1 0x7f062c8fc4db logging::LogMessage::~LogMessage() #2 0x7f06304611a6 gpu::GpuChannelMessageFilter::OnFilterRemoved() #3 0x7f062d3c43e9 IPC::ChannelProxy::Context::OnChannelClosed() #4 0x7f062af08fe3 _ZN4base8internal7InvokerINS0_9BindStateIMN7content21EmbeddedServiceRunner15InstanceManagerEFvvEJ13scoped_refptrIS5_EEEEFvvEE3RunEPNS0_13BindStateBaseE #5 0x7f062c97cb36 base::debug::TaskAnnotator::RunTask() #6 0x7f062c903f8d base::MessageLoop::RunTask() #7 0x7f062c9042a8 base::MessageLoop::DeferOrRunPendingTask() #8 0x7f062c9045db base::MessageLoop::DoWork() #9 0x7f062c907199 base::MessagePumpLibevent::Run() #10 0x7f062c903c71 base::MessageLoop::RunHandler() #11 0x7f062c926470 base::RunLoop::Run() #12 0x7f062c94f71f base::Thread::Run() #13 0x7f062c94fbf4 base::Thread::ThreadMain() #14 0x7f062c948a8c base::(anonymous namespace)::ThreadFunc() #15 0x7f0629e53e9a start_thread #16 0x7f0623ab536d clone Received signal 6 #0 0x7f062c8e2ac7 base::debug::(anonymous namespace)::StackDumpSignalHandler() #1 0x7f0629e5bcb0 <unknown> #2 0x7f06239f8035 gsignal #3 0x7f06239fb79b abort #4 0x7f062c8e19e2 base::debug::BreakDebugger() #5 0x7f062c8fc776 logging::LogMessage::~LogMessage() #6 0x7f06304611a6 gpu::GpuChannelMessageFilter::OnFilterRemoved() #7 0x7f062d3c43e9 IPC::ChannelProxy::Context::OnChannelClosed() #8 0x7f062af08fe3 _ZN4base8internal7InvokerINS0_9BindStateIMN7content21EmbeddedServiceRunner15InstanceManagerEFvvEJ13scoped_refptrIS5_EEEEFvvEE3RunEPNS0_13BindStateBaseE #9 0x7f062c97cb36 base::debug::TaskAnnotator::RunTask() #10 0x7f062c903f8d base::MessageLoop::RunTask() #11 0x7f062c9042a8 base::MessageLoop::DeferOrRunPendingTask() #12 0x7f062c9045db base::MessageLoop::DoWork() #13 0x7f062c907199 base::MessagePumpLibevent::Run() #14 0x7f062c903c71 base::MessageLoop::RunHandler() #15 0x7f062c926470 base::RunLoop::Run() #16 0x7f062c94f71f base::Thread::Run() #17 0x7f062c94fbf4 base::Thread::ThreadMain() #18 0x7f062c948a8c base::(anonymous namespace)::ThreadFunc() #19 0x7f0629e53e9a start_thread #20 0x7f0623ab536d clone r8: 000019ae0ba4dd18 r9: 0000000000000000 r10: 0000000000000008 r11: 0000000000000206 r12: 00007f0616e40dc8 r13: 000019ae07b6a340 r14: 00007f0616e41218 r15: 00007f0616e41208 di: 0000000000000a3a si: 0000000000000a4e bp: 00007f0631127dc7 bx: 00007f062c8a99b0 dx: 0000000000000006 ax: 0000000000000000 cx: ffffffffffffffff sp: 00007f0616e40c68 ip: 00007f06239f8035 efl: 0000000000000206 cgf: 000c000000000033 erf: 0000000000000000 trp: 0000000000000000 msk: 0000000000000000 cr2: 0000000000000000 [end of stack trace] [2575:2589:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for news.ycombinator.com failed err=-8172 [2575:2589:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for developer.nintendo.com failed err=-8172 [2575:2590:1108/173026:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for ssl.gstatic.com failed err=-8172 LOG: [1:1:1108/173016:ERROR:memory_mapped_file.cc(52)] Couldn't open /b/swarm_slave/w/irOUEzaN/out/Release/chrome_200_percent.pak [1:1:1108/173016:ERROR:data_pack.cc(79)] Failed to mmap datapack [2575:2575:1108/173016:ERROR:memory_mapped_file.cc(52)] Couldn't open /b/swarm_slave/w/irOUEzaN/out/Release/chrome_200_percent.pak [2575:2575:1108/173016:ERROR:data_pack.cc(79)] Failed to mmap datapack [2575:2575:1108/173016:WARNING:audio_manager.cc(317)] Multiple instances of AudioManager detected [2575:2575:1108/173016:WARNING:audio_manager.cc(278)] Multiple instances of AudioManager detected [2575:2575:1108/173016:WARNING:password_store_factory.cc(248)] Using basic (unencrypted) store for password storage. See https://chromium.googlesource.com/chromium/src/+/master/docs/linux_password_storage.md for more information about password storage options. [2575:2590:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for chromium-i18n.appspot.com failed err=-8172 [2618:2618:1108/173017:ERROR:sandbox_linux.cc(343)] InitializeSandbox() called with multiple threads in process gpu-process. [2575:2598:1108/173017:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel. [2618:2638:1108/173017:FATAL:gpu_channel.cc(460)] Check failed: channel_. #0 0x7f062c8e2f2e base::debug::StackTrace::StackTrace() #1 0x7f062c8fc4db logging::LogMessage::~LogMessage() #2 0x7f06304611a6 gpu::GpuChannelMessageFilter::OnFilterRemoved() #3 0x7f062d3c43e9 IPC::ChannelProxy::Context::OnChannelClosed() #4 0x7f062af08fe3 _ZN4base8internal7InvokerINS0_9BindStateIMN7content21EmbeddedServiceRunner15InstanceManagerEFvvEJ13scoped_refptrIS5_EEEEFvvEE3RunEPNS0_13BindStateBaseE #5 0x7f062c97cb36 base::debug::TaskAnnotator::RunTask() #6 0x7f062c903f8d base::MessageLoop::RunTask() #7 0x7f062c9042a8 base::MessageLoop::DeferOrRunPendingTask() #8 0x7f062c9045db base::MessageLoop::DoWork() #9 0x7f062c907199 base::MessagePumpLibevent::Run() #10 0x7f062c903c71 base::MessageLoop::RunHandler() #11 0x7f062c926470 base::RunLoop::Run() #12 0x7f062c94f71f base::Thread::Run() #13 0x7f062c94fbf4 base::Thread::ThreadMain() #14 0x7f062c948a8c base::(anonymous namespace)::ThreadFunc() #15 0x7f0629e53e9a start_thread #16 0x7f0623ab536d clone [2575:2589:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for news.ycombinator.com failed err=-8172 [2575:2657:1108/173017:WARNING:simple_synchronous_entry.cc(1055)] Could not open platform files for entry. [2575:2589:1108/173017:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for developer.nintendo.com failed err=-8172 [2575:2590:1108/173026:ERROR:cert_verify_proc_nss.cc(942)] CERT_PKIXVerifyCert for ssl.gstatic.com failed err=-8172 To debug this, you can add DLOG(WARNING) to get more info about why this is failing on you CL.
,
Dec 21 2016
,
Jan 11 2017
Grabbing this because I also have https://bugs.chromium.org/p/chromium/issues/detail?id=657665, but realistically I have no idea when I'm going to get to it.
,
Jan 11 2017
sunyunjia@: could you please rerun the test on linux ToT. IIRC, this bug happened for our CL that changed the default auto behavior (crrev.com/2467913002). We reverted that behavior later on.
,
Jan 11 2017
,
Jan 12 2017
The following revision refers to this bug: https://chromium.googlesource.com/chromium/src.git/+/20b0d38e48ac6294d88377f1b09fbd54eb7704f4 commit 20b0d38e48ac6294d88377f1b09fbd54eb7704f4 Author: sunyunjia <sunyunjia@chromium.org> Date: Thu Jan 12 14:39:00 2017 Re-enable hackernews telemetry test on linux. Previously, we disabled the hackernews telemetry test as we tried to set the default value of touch-events flag to enabled. Now we've changed the strategy and reset the default value to auto. And recently, we also passed the test with the flag set to be enabled. So the hackernews telemetry test no longer needs to be disabled. BUG= 665007 Review-Url: https://codereview.chromium.org/2627163003 Cr-Commit-Position: refs/heads/master@{#443227} [modify] https://crrev.com/20b0d38e48ac6294d88377f1b09fbd54eb7704f4/tools/perf/page_sets/system_health/browsing_stories.py
,
Jan 12 2017
|
||||||
►
Sign in to add a comment |
||||||
Comment 1 by mustaq@chromium.org
, Nov 15 2016Status: Available (was: Untriaged)