New issue
Advanced search Search tips

Issue 691751 link

Starred by 1 user

Issue metadata

Status: Fixed
Owner:
Closed: Feb 2017
Cc:
EstimatedDays: ----
NextAction: ----
OS: ----
Pri: 1
Type: ----



Sign in to add a comment

rasterize_and_record_micro.key_mobile_sites failing on chromium.perf/Linux Perf

Project Member Reported by sullivan@chromium.org, Feb 13 2017

Issue description

rasterize_and_record_micro.key_mobile_sites failing on chromium.perf/Linux Perf

Builders failed on: 
- Linux Perf: 
  https://build.chromium.org/p/chromium.perf/builders/Linux%20Perf


Failed at: 447227:447303

Logs:
[ RUN      ] http://shop.mobileweb.ebay.com/searchresults?kw=viking+helmet
INFO:root:Starting Web-Page-Replay: ['/usr/bin/python', '/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/web-page-replay/replay.py', '--host=127.0.0.1', '--port=0', '--ssl_port=0', '--no-dns_forwarding', '--use_closest_match', '--log_level=info', u'/b/s/w/irZscfll/tools/perf/page_sets/data/key_mobile_sites_000.wpr']
INFO:root:WPR ports: {'http': 52865, 'https': 38484}
INFO:root:Issuing command to ts_proxy_server: set mapports 443:38484,*:52865
INFO:root:Requested remote debugging port: 0
INFO:root:Starting Chrome ['/b/s/w/irZscfll/out/Release/chrome', '--enable-gpu-benchmarking', u'--enable-features=AutofillCreditCardSigninPromo,AutofillProfileCleanup,DocumentWriteEvaluator,EnableSyncClientToServerCompression,EnableSyncUSSDeviceInfo,ExpectCTReporting,ExpensiveBackgroundTimerThrottling,FasterLocationReload,PreferHtmlOverPlugins,HttpFormWarning,MaterialDesignUserManager,PrioritySupportedRequestsDelayable,NetworkTimeServiceQuerying,ParseHTMLOnMainThread,PassiveDocumentEventListeners,PassiveEventListenersDueToFling,UsePasswordSeparatedSigninFlow,DisplayPersistenceToggleInPermissionPrompts,PersistentHistograms,PointerEvent,PreconnectMore,PrintPdfAsImage,PrintScaling,RafAlignedMouseInput,RafAlignedTouchInput,IncidentReportingDisableUpload,IncidentReportingModuleLoadAnalysis,IncidentReportingSuspiciousModuleReporting,CanShowScoutOptIn,SecurityChip,SecurityWarningIconUpdate,SpeculativeLaunchServiceWorker,NegotiateTLS13,token-binding,TranslateRankerEnforcement,WebRTC-EnableWebRtcEcdsa,WebRTC-H264WithOpenH264FFmpeg,TunedClippingLevelMin30,use-new-media-cache', u'--force-fieldtrial-params=AutofillCreditCardSigninPromo.EnabledThree:impression_limit/3,AutofillProfileOrderByFrecency.EnabledLimitTo3:limit/3,BrowserScheduler.Default:RedirectSequencedWorkerPools/true,CSSExternalScanner.Enabled_ScanAndPreload:cssExternalScannerNoPreload/false/cssExternalScannerPreload/true,DisallowFetchForDocWrittenScriptsInMainFrame.DocumentWriteEvaluatorGroup:disallowFetchForDocWrittenScriptsInMainFrame/false/disallowFetchForDocWrittenScriptsInMainFrameOnSlowConnections/false,ExpensiveBackgroundTimerThrottling.Enabled_30sMaxThrottilingDelay:cpu_budget/0%2E01/initial_budget/1%2E0/max_budget/3%2E0/max_delay/30%2E0,Html5ByDefault.Enabled:engagement_threshold_for_flash/1,NetProxyPreconnects.NetProxyPreconnects:restrict_to_one_preconnect_for_proxies/true,NetworkTimeQueries.NetworkTimeQueriesEnabledBackgroundAndOnDemand:FetchBehavior/background-and-on-demand,ParseHTMLOnMainThread.Enabled:parseHTMLOnMainThreadCoalesceChunks/false/parseHTMLOnMainThreadSyncTokenize/false,PasswordSmartBubble.3-Times:dismissal_count/3,PersistentHistograms.EnabledInMemory:storage/LocalMemory,ReportCertificateErrors.ShowAndPossiblySend:sendingThreshold/1%2E0,SafeBrowsingIncidentReportingService.Enabled:collect_reg_data/true,SecurityChip.Enabled:animation/animate-nonsecure-only/visibility/show-all,SignInPasswordPromo.Enable3:dismissal_threshold/3,WebBluetoothBlocklist.TestGroup:blocklist_additions/00060000%3Ae%2Cfffd%3Ae%2Ced5f25a4%3Ae', u'--force-fieldtrials=AsyncDNS/AsyncDNSA/AutofillClassifier/Enabled/AutofillCreditCardSigninPromo/EnabledThree/AutofillFieldMetadata/Enabled/AutofillProfileCleanup/Enabled/AutofillProfileOrderByFrecency/EnabledLimitTo3/BrowserScheduler/Default/CSSExternalScanner/Enabled_ScanAndPreload/CaptivePortalInterstitial/Enabled/ChromeDashboard/Enabled/ChromotingQUIC/Enabled/DataReductionProxyUseQuic/Enabled/DisallowFetchForDocWrittenScriptsInMainFrame/DocumentWriteEvaluatorGroup/EnableGoogleCachedCopyTextExperiment/Button/EnableMediaRouter/Enabled/EnableMediaRouterWithCastExtension/Enabled/EnableSessionCrashedBubbleUI/Enabled/EnableSyncClientToServerCompression/Enabled/EnableSyncUSSDeviceInfo/Enabled/ExpectCTReporting/ExpectCTReportingEnabled/ExpensiveBackgroundTimerThrottling/Enabled_30sMaxThrottilingDelay/ExtensionActionRedesign/Enabled/FasterLocationReload/Enabled/GoogleBrandedContextMenu/branded/Html5ByDefault/Enabled/HttpFormWarning/Enabled/InstanceID/Enabled/MarkNonSecureAs/show-non-secure-passwords-cc-ui/MaterialDesignDownloads/Enabled/MaterialDesignUserManager/Enabled/MojoChannel/Enabled/NetDelayableH2AndQuicRequests/Enabled/NetProxyPreconnects/NetProxyPreconnects/NetworkTimeQueries/NetworkTimeQueriesEnabledBackgroundAndOnDemand/OfferUploadCreditCards/Enabled/OutOfProcessPac/Enabled/PageRevisitInstrumentation/Enabled/ParseHTMLOnMainThread/Enabled/PassiveDocumentEventListeners/Enabled/PassiveEventListenersDueToFling/Enabled/PasswordBranding/SmartLockBrandingSavePromptOnly/PasswordManagerSettingsMigration/Enable/PasswordSeparatedSigninFlow/Enabled/PasswordSmartBubble/3-Times/PermissionPromptUIViews/ToggleEnabled/PersistentHistograms/EnabledInMemory/PointerEvent/Enabled/PreconnectMore/Enabled/PrintPdfAsImage/Enabled/PrintScaling/Enabled/QUIC/Enabled/RafAlignedMouseInput/Enabled/RafAlignedTouchInput/Enabled/RefreshTokenDeviceId/Enabled/ReportCertificateErrors/ShowAndPossiblySend/SSLCommonNameMismatchHandling/Enabled/SafeBrowsingIncidentReportingService/Enabled/SafeBrowsingIncidentReportingServiceFeatures/WithSuspiciousModuleReporting/SafeBrowsingScoutTransitionStudy/CanShowScoutOptInGroup/SafeBrowsingV4LocalDatabaseManagerEnabled/Enabled/SaveAsMenuText/download/SchedulerExpensiveTaskBlocking/Enabled/SdchPersistence/Enabled/SecurityChip/Enabled/SecurityWarningIconUpdate/Enabled/SignInPasswordPromo/Enable3/SimpleCacheTrial/ExperimentYes/SiteIsolationForExtensions/Enabled/SpeculativeLaunchServiceWorker/Enabled/StrictSecureCookies/Enabled/TLS13Negotiation/Enabled/TabSyncByRecency/Enabled/TokenBinding/TokenBinding/TranslateRanker/EnforcementEnabled/V8CacheStrategiesForCacheStorage/default/WebBluetoothBlocklist/TestGroup/WebFontsInterventionV2/Enabled-slow2g/WebRTC-EnableWebRtcEcdsa/Enabled/WebRTC-H264WithOpenH264FFmpeg/Enabled/WebRTC-LocalIPPermissionCheck/Enabled/WebRTC-TunedClippingLevelMin-SessionRandomized/Enabled/use-new-media-cache/Enabled', '--enable-net-benchmarking', '--metrics-recording-only', '--no-default-browser-check', '--no-first-run', '--enable-gpu-benchmarking', '--disable-background-networking', '--proxy-server=socks://localhost:58450', '--ignore-certificate-errors', '--user-agent=Mozilla/5.0 (Linux; Android 4.0.4; Galaxy Nexus Build/IMM76B) AppleWebKit/535.36 (KHTML, like Gecko) Chrome/40.0.2194.2 Mobile Safari/535.36', '--disable-component-extensions-with-background-pages', '--disable-default-apps', '--remote-debugging-port=0', '--enable-crash-reporter-for-testing', '--window-size=1280,1024', '--user-data-dir=/b/s/w/itw3WEnF/tmpzVPZbo', 'about:blank']
INFO:root:Discovered ephemeral port 37866
INFO:root:OS: linux trusty
INFO:root:GPU device 0: VENDOR = 0x102b, DEVICE = 0x534
INFO:root:GPU Attributes:
INFO:root:  adapter_luid        : 0
INFO:root:  amd_switchable      : False
INFO:root:  basic_info_state    : 1
INFO:root:  context_info_state  : 1
INFO:root:  direct_rendering    : True
INFO:root:  driver_date         : 
INFO:root:  driver_vendor       : Mesa
INFO:root:  driver_version      : 10.1.3
INFO:root:  gl_extensions       : GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_compression_s3tc GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_NV_fog_distance GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_MESA_ycbcr_texture GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_ATI_texture_compression_3dc GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_mirror_clamp GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_packed_depth_stencil GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_ATI_texture_mirror_once GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_compression_latc GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_shader_bit_encoding GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_NV_vdpau_interop GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_ARB_transform_feedback_instanced GL_EXT_transform_feedback GL_AMD_shader_trinary_minmax GL_ARB_clear_buffer_object GL_ARB_invalidate_subdata GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev 
INFO:root:  gl_renderer         : Gallium 0.4 on llvmpipe (LLVM 3.4, 256 bits)
INFO:root:  gl_reset_notification_strategy: 33377
INFO:root:  gl_vendor           : VMware, Inc.
INFO:root:  gl_version          : 2.1 Mesa 10.1.3
INFO:root:  gl_ws_extensions    : GLX_ARB_multisample GLX_EXT_visual_info GLX_EXT_visual_rating GLX_EXT_import_context GLX_EXT_texture_from_pixmap GLX_OML_swap_method GLX_SGI_make_current_read GLX_SGIS_multisample GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_MESA_copy_sub_buffer 
INFO:root:  gl_ws_vendor        : SGI
INFO:root:  gl_ws_version       : 1.4
INFO:root:  in_process_gpu      : False
INFO:root:  initialization_time : 0.020361
INFO:root:  jpeg_decode_accelerator_supported: False
INFO:root:  lenovo_dcute        : False
INFO:root:  max_msaa_samples    : 0
INFO:root:  optimus             : False
INFO:root:  passthrough_cmd_decoder: False
INFO:root:  pixel_shader_version: 1.30
INFO:root:  process_crash_count : 0
INFO:root:  rgba_visual         : 66
INFO:root:  sandboxed           : False
INFO:root:  software_rendering  : False
INFO:root:  system_visual       : 33
INFO:root:  vertex_shader_version: 1.30
INFO:root:  video_decode_accelerator_flags: 0
INFO:root:Feature Status:
INFO:root:  2d_canvas           : unavailable_software
INFO:root:  flash_3d            : unavailable_software
INFO:root:  flash_stage3d       : unavailable_software
INFO:root:  flash_stage3d_baseline: unavailable_software
INFO:root:  gpu_compositing     : unavailable_software
INFO:root:  multiple_raster_threads: unavailable_off
INFO:root:  native_gpu_memory_buffers: disabled_software
INFO:root:  rasterization       : unavailable_software
INFO:root:  video_decode        : unavailable_software
INFO:root:  video_encode        : unavailable_software
INFO:root:  vpx_decode          : unavailable_software
INFO:root:  webgl               : unavailable_off
INFO:root:  webgl2              : unavailable_off
INFO:root:Driver Bug Workarounds:
INFO:root:  clear_uniforms_before_first_program_use
INFO:root:  count_all_in_varyings_packing
INFO:root:  decode_encode_srgb_for_generatemipmap
INFO:root:  disable_framebuffer_cmaa
INFO:root:  disable_post_sub_buffers_for_onscreen_surfaces
INFO:root:  disable_texture_storage
INFO:root:  scalarize_vec_and_mat_constructor_args
INFO:root:Issuing command to ts_proxy_server: set rtt 0
INFO:root:Issuing command to ts_proxy_server: set inkbps 0
INFO:root:Issuing command to ts_proxy_server: set outkbps 0
INFO:root:PageCacheTemperature: any
WARNING:root:No path to crashpad_database_util found
INFO:root:No minidump found via crashpad_database_util
WARNING:root:No path to crashpad_database_util found
INFO:root:No minidump found via crashpad_database_util
INFO:root:*************** BROWSER STANDARD OUTPUT ***************
INFO:root:[5285:5305:0131/165457.477594:ERROR:cert_verify_proc_nss.cc(918)] CERT_PKIXVerifyCert for chromium-i18n.appspot.com failed err=-8172
[5342:5342:0131/165457.500250:ERROR:sandbox_linux.cc(343)] InitializeSandbox() called with multiple threads in process gpu-process. 
[5285:5318:0131/165457.502084:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
[5285:5318:0131/165457.502118:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
[5285:5318:0131/165457.502144:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
[5285:5318:0131/165457.502166:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
[5285:5318:0131/165457.502188:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
A Parser-blocking, cross site (i.e. different eTLD+1) script, http://www.google.com/ads/search/module/ads/3.0/62925b8ff6a415c526cadef638c7fde31f1f3fe4/n/search.js, is invoked via document.write. This MAY be blocked by the browser in this or a future page load due to poor network connectivity. If blocked in this page load, it will be confirmed in a subsequent console message.See https://www.chromestatus.com/feature/5718547946799104 for more details.
A Parser-blocking, cross site (i.e. different eTLD+1) script, http://www.google.com/ads/search/module/ads/3.0/62925b8ff6a415c526cadef638c7fde31f1f3fe4/n/search.js, is invoked via document.write. This MAY be blocked by the browser in this or a future page load due to poor network connectivity. If blocked in this page load, it will be confirmed in a subsequent console message.See https://www.chromestatus.com/feature/5718547946799104 for more details.
[5285:5307:0131/165507.910845:ERROR:cert_verify_proc_nss.cc(918)] CERT_PKIXVerifyCert for ssl.gstatic.com failed err=-8172

INFO:root:*********** END OF BROWSER STANDARD OUTPUT ************
INFO:root:********************* BROWSER LOG *********************
INFO:root:No log file
INFO:root:***************** END OF BROWSER LOG ******************
Traceback (most recent call last):
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/story_runner.py", line 94, in _RunStoryAndProcessErrorIfNeeded
    state.RunStory(results)
  File "/b/s/w/irZscfll/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/s/w/irZscfll/third_party/catapult/telemetry/telemetry/page/shared_page_state.py", line 301, in RunStory
    self._current_page, self._current_tab, results)
  File "/b/s/w/irZscfll/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/s/w/irZscfll/tools/perf/measurements/rasterize_and_record_micro.py", line 55, in ValidateAndMeasurePage
    benchmark_id = tab.EvaluateJavaScript2('window.benchmark_results.id')
  File "/b/s/w/irZscfll/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/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 175, in EvaluateJavaScript2
    return self._inspector_backend.EvaluateJavaScript2(*args, **kwargs)
  File "/b/s/w/irZscfll/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/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 40, in inner
    inspector_backend._ConvertExceptionFromInspectorWebsocket(e)
  File "/b/s/w/irZscfll/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/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 37, in inner
    return func(inspector_backend, *args, **kwargs)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 244, in EvaluateJavaScript2
    return self._runtime.Evaluate(expression, context_id, timeout)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_runtime.py", line 45, in Evaluate
    res = self._inspector_websocket.SyncRequest(request, timeout)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 110, in SyncRequest
    res = self._Receive(timeout)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 149, in _Receive
    data = self._socket.recv()
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 596, in recv
    opcode, data = self.recv_data()
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 606, in recv_data
    frame = self.recv_frame()
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 637, in recv_frame
    self._frame_header = self._recv_strict(2)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 746, in _recv_strict
    bytes = self._recv(shortage)
  File "/b/s/w/irZscfll/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 732, in _recv
    raise WebSocketTimeoutException(e.message)
TimeoutException: 
********************************************************************************
(/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:487 _ConvertExceptionFromInspectorWebsocket) The app is probably crashed:

Found Minidump: False
Stack Trace:
********************************************************************************
	No crash dump found.
********************************************************************************
Standard output:
********************************************************************************
	[5285:5305:0131/165457.477594:ERROR:cert_verify_proc_nss.cc(918)] CERT_PKIXVerifyCert for chromium-i18n.appspot.com failed err=-8172
	[5342:5342:0131/165457.500250:ERROR:sandbox_linux.cc(343)] InitializeSandbox() called with multiple threads in process gpu-process. 
	[5285:5318:0131/165457.502084:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
	[5285:5318:0131/165457.502118:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
	[5285:5318:0131/165457.502144:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
	[5285:5318:0131/165457.502166:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
	[5285:5318:0131/165457.502188:ERROR:browser_gpu_channel_host_factory.cc(125)] Failed to create channel.
	A Parser-blocking, cross site (i.e. different eTLD+1) script, http://www.google.com/ads/search/module/ads/3.0/62925b8ff6a415c526cadef638c7fde31f1f3fe4/n/search.js, is invoked via document.write. This MAY be blocked by the browser in this or a future page load due to poor network connectivity. If blocked in this page load, it will be confirmed in a subsequent console message.See https://www.chromestatus.com/feature/5718547946799104 for more details.
	A Parser-blocking, cross site (i.e. different eTLD+1) script, http://www.google.com/ads/search/module/ads/3.0/62925b8ff6a415c526cadef638c7fde31f1f3fe4/n/search.js, is invoked via document.write. This MAY be blocked by the browser in this or a future page load due to poor network connectivity. If blocked in this page load, it will be confirmed in a subsequent console message.See https://www.chromestatus.com/feature/5718547946799104 for more details.
	[5285:5307:0131/165507.910845:ERROR:cert_verify_proc_nss.cc(918)] CERT_PKIXVerifyCert for ssl.gstatic.com failed err=-8172
********************************************************************************
System log:
(Not implemented)
********************************************************************************
(/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:492 _ConvertExceptionFromInspectorWebsocket) Original exception:
timed out
********************************************************************************
(/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:513 _AddDebuggingInformation) Received a socket error in the browser connection and the tab still exists. The operation probably timed out.
********************************************************************************
(/b/s/w/irZscfll/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:514 _AddDebuggingInformation) Debugger url: ws://127.0.0.1:37866/devtools/page/81762a0f-f998-447f-89a4-917548e8e9ee

[  FAILED  ] http://shop.mobileweb.ebay.com/searchresults?kw=viking+helmet (83084 ms)
 
Project Member

Comment 2 by 42576172...@developer.gserviceaccount.com, Feb 14 2017

Cc: perezju@chromium.org
Owner: perezju@chromium.org

=== Auto-CCing suspected CL author perezju@chromium.org ===

Hi perezju@chromium.org, the bisect results pointed to your CL, please take a look at the
results.


=== BISECT JOB RESULTS ===
Test failure found with culprit

Suspected Commit
  Author : perezju
  Commit : 2445daf09f96a27c16e93f20c40eb30acb4a6fa9
  Date   : Tue Jan 31 17:39:58 2017
  Subject: [tools/perf] Migrate clients to new JavaScript eval API (batch 1)

Bisect Details
  Configuration: linux_perf_bisect
  Benchmark    : rasterize_and_record_micro.key_mobile_sites
  Metric       : record_time/http___shop.mobileweb.ebay.com_searchresults?kw_viking+helmet

Revision             Exit Code      N
chromium@447226      0 +- N/A       20      good
chromium@447246      0 +- N/A       20      good
chromium@447256      0 +- N/A       20      good
chromium@447259      0 +- N/A       20      good
chromium@447260      0 +- N/A       20      good
chromium@447261      1 +- N/A       20      bad       <--
chromium@447265      1 +- N/A       20      bad
chromium@447303      1 +- N/A       20      bad

To Run This Test
  src/tools/perf/run_benchmark -v --browser=release --output-format=chartjson --upload-results --pageset-repeat=1 --also-run-disabled-tests --story-filter=http...shop.mobileweb.ebay.com.searchresults.kw.viking.helmet rasterize_and_record_micro.key_mobile_sites

Debug Info
  https://chromeperf.appspot.com/buildbucket_job_status/8987737254904214080

Is this bisect wrong?
  https://chromeperf.appspot.com/bad_bisect?try_job_id=5908775606681600


| O O | Visit http://www.chromium.org/developers/speed-infra/perf-bug-faq
|  X  | for more information addressing perf regression bugs. For feedback,
| / \ | file a bug with component Speed>Bisection.  Thank you!
Sent out CL to fix this: https://codereview.chromium.org/2691023003/
Project Member

Comment 4 by bugdroid1@chromium.org, Feb 14 2017

The following revision refers to this bug:
  https://chromium.googlesource.com/chromium/src.git/+/58b5ae9d8d96fd72374c5ee63a7c8ad358eec9e8

commit 58b5ae9d8d96fd72374c5ee63a7c8ad358eec9e8
Author: perezju <perezju@chromium.org>
Date: Tue Feb 14 11:53:50 2017

Fix timeout in rasterize_and_record_micro benchmarks

A bug was introduced when migrating to the v2 API to execute JavaScript
snippets in Telemetry. The old timeout used inconsistent defaults on
different methods, and the migration caused the timeout to drop from 90
to 60 seconds.

Rather than relying on an unseen defualt, specify an explicit timeout
on the non-obvious expression that takes a long time to evaluate.

TBR=nednguyen@google.com
BUG= 691751 

Review-Url: https://codereview.chromium.org/2691023003
Cr-Commit-Position: refs/heads/master@{#450325}

[modify] https://crrev.com/58b5ae9d8d96fd72374c5ee63a7c8ad358eec9e8/tools/perf/measurements/rasterize_and_record_micro.py

Status: Fixed (was: Available)
Fixed as of: https://build.chromium.org/p/chromium.perf/builders/Linux%20Perf/builds/366

Sign in to add a comment