New issue
Advanced search Search tips

Issue 665007 link

Starred by 2 users

Issue metadata

Status: Fixed
Owner:
Closed: Jan 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Linux , Windows
Pri: 3
Type: Bug

Blocking:
issue 664505



Sign in to add a comment

hackernews in telemetry_perf_unittests fails when touch_event_api flag is enabled

Project Member Reported by sunyunjia@chromium.org, Nov 14 2016

Issue description

In 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.
 

Comment 1 by mustaq@chromium.org, Nov 15 2016

Components: Tests>Telemetry
Status: Available (was: Untriaged)
A bit more details: the test times out when loading the first link (https://developer.nintendo.com/) from the hackernews page (https://news.ycombinator.com).

Manual testing with either sites seemed fine in our experimental build.
Do you have the browser log/crash stack when the test fails?

Comment 3 by mustaq@chromium.org, 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.

Comment 4 by mustaq@chromium.org, Nov 18 2016

Cc: sunyunjia@chromium.org
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.

Comment 5 by mustaq@chromium.org, 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:

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.
Blocking: 664505
Owner: charliea@chromium.org
Status: Assigned (was: Available)
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.

Comment 9 by mustaq@chromium.org, 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.
Owner: sunyunjia@chromium.org
Project Member

Comment 11 by bugdroid1@chromium.org, 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

Status: Fixed (was: Assigned)

Sign in to add a comment