Closed Bug 1729175 Opened 4 years ago Closed 1 year ago

Intermittent TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398'

Categories

(Testing :: Talos, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=350574480&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WQIlVkziQzyrzeVYye-qRg/runs/0/artifacts/public/logs/live_backing.log


[task 2021-09-04T22:56:42.347Z] 22:56:42     INFO -  TEST-START | tabswitch
task 2021-09-04T22:56:42.348Z] 22:56:42     INFO -  Initialising browser for tabswitch test...
[task 2021-09-04T22:56:42.348Z] 22:56:42     INFO -  Cloning profile located at C:\Users\task_1630758425\build\tests\talos\talos\base_profile
[task 2021-09-04T22:56:42.350Z] 22:56:42     INFO -  Merging profile: C:\Users\task_1630758425\build\tests\talos\talos\profile_data\base
[task 2021-09-04T22:56:42.352Z] 22:56:42     INFO -  Merging profile: C:\Users\task_1630758425\build\tests\talos\talos\profile_data\common
[task 2021-09-04T22:56:42.359Z] 22:56:42     INFO -  Merging profile: C:\Users\task_1630758425\build\tests\talos\talos\profile_data\perf
[task 2021-09-04T22:56:42.364Z] 22:56:42     INFO -  Installing Add-ons:
[task 2021-09-04T22:56:42.365Z] 22:56:42     INFO -  ['C:\\Users\\task_1630758425\\build\\tests\\talos\\talos/talos-powers', 'C:\\Users\\task_1630758425\\build\\tests\\talos\\talos/tests/tabswitch', 'C:\\Users\\task_1630758425\\build\\tests\\talos\\talos/pageloader']
[task 2021-09-04T22:56:42.397Z] 22:56:42     INFO -  Installing Webextensions:
[task 2021-09-04T22:56:42.398Z] 22:56:42     INFO -  Application command: C:\Users\task_1630758425\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49972/getInfo.html --wait-for-browser -profile C:\Users\task_1630758425\AppData\Local\Temp\tmpl0pdj55t\profile
[task 2021-09-04T22:56:42.406Z] 22:56:42     INFO -  TEST-INFO | started process 5336 (C:\Users\task_1630758425\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49972/getInfo.html)
[task 2021-09-04T22:56:44.509Z] 22:56:44     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1630758425\\AppData\\Local\\Temp\\tmpl0pdj55t\\profile\\search.json.mozlz4", (void 0)))
[task 2021-09-04T22:56:44.769Z] 22:56:44     INFO -  __metrics
[task 2021-09-04T22:56:44.770Z] 22:56:44     INFO -  	Screen width/height: 1024/768
[task 2021-09-04T22:56:44.770Z] 22:56:44     INFO -  	colorDepth: 24
[task 2021-09-04T22:56:44.770Z] 22:56:44     INFO -  	Browser inner width/height: 1024/682
[task 2021-09-04T22:56:44.770Z] 22:56:44     INFO -  __metrics
[task 2021-09-04T22:56:44.859Z] 22:56:44     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 690: WebGL context was lost.
[task 2021-09-04T22:56:44.875Z] 22:56:44     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 690: WebGL context was lost.
[task 2021-09-04T22:56:45.194Z] 22:56:45     INFO -  about:support	{"application":{"name":"Firefox","osVersion":"Windows_NT 10.0 17134","version":"93.0a1","buildID":"20210904212348","distributionID":"","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:93.0) Gecko/20100101 Firefox/93.0","safeMode":false,"updateChannel":"nightly-autoland","supportURL":"https://support.mozilla.org/1/firefox/93.0a1/WINNT/en-US/","osTheme":"","numTotalWindows":1,"numFissionWindows":1,"numRemoteWindows":1,"launcherProcessState":0,"fissionAutoStart":true,"fissionDecisionStatus":"enabledByUserPref","remoteAutoStart":true,"policiesStatus":0,"keyLocationServiceGoogleFound":true,"keySafebrowsingGoogleFound":true,"keyMozillaFound":true},"securitySoftware":{"registeredAntiVirus":"","registeredAntiSpyware":"","registeredFirewall":"Windows Firewall"},"processes":{"remoteTypes":{"webIsolated":1,"extension":1,"gpu":1},"maxWebContentProcesses":8},"environmentVariables":{"MOZ_AUTOMATION":"1","MOZ_CRASHREPORTER_DATA_DIRECTORY":"C:\\Users\\task_1630758425\\AppData\\Roaming\\Mozilla\\Firefox\\Crash Reports","MOZ_CRASHREPORTER_EVENTS_DIRECTORY":"C:\\Users\\task_1630758425\\AppData\\Local\\Temp\\tmpl0pdj55t\\profile\\crashes\\events","MOZ_CRASHREPORTER_PING_DIRECTORY":"C:\\Users\\task_1630758425\\AppData\\Roaming\\Mozilla\\Firefox\\Pending Pings","MOZ_CRASHREPORTER_RESTART_ARG_0":"C:\\Users\\task_1630758425\\build\\application\\firefox\\firefox.exe","MOZ_CRASHREPORTER_RESTART_ARG_1":"http://127.0.0.1:49972/getInfo.html","MOZ_CRASHREPORTER_RESTART_ARG_2":"--wait-for-browser","MOZ_CRASHREPORTER_RESTART_ARG_3":"-profile","MOZ_CRASHREPORTER_RESTART_ARG_4":"C:\\Users\\task_1630758425\\AppData\\Local\\Temp\\tmpl0pdj55t\\profile","MOZ_CRASHREPORTER_STRINGS_OVERRIDE":"C:\\Users\\task_1630758425\\build\\application\\firefox\\browser\\crashreporter-override.ini","MOZ_FETCHES":"[{\"artifact\": \"public/build/fix-stacks.tar.bz2\", \"extract\": true, \"task\": \"ZbYpPkHDQNG1LAuQuC_PCA\"}, {\"artifact\": \"public/build/minidump_stackwalk.tar.xz\", \"extract\": true, \"task\": \"KdzjeuAyTXGSZLEuSuylZA\"}]","MOZ_FETCHES_DIR":"C:/Users/task_1630758425/fetches","MOZ_SCM_LEVEL":"3","MOZ_UPLOAD_DIR":"C:\\Users\\task_1630758425\\build\\blobber_upload_dir","MOZ_CRASHREPORTER_NO_REPORT":"1","MOZ_CRASHREPORTER":"1","MOZ_DISABLE_NONLOCAL_CONNECTIONS":"1","MOZ_NO_REMOTE":"1","XRE_NO_WINDOWS_CRASH_DIALOG":"1"},"modifiedPreferences":{"browser.cache.disk.smart_size.enabled":false,"browser.contentblocking.category":"custom","browser.link.open_newwindow":2,"browser.search.region":"US","browser.startup.homepage_override.mstone":"93.0a1","browser.startup.homepage_override.buildID":"20210904212348","browser.tabs.remote.autostart":true,"browser.tabs.remote.systemTriggeredAboutBlankAnywhere":true,"browser.tabs.unloadOnLowMemory":false,"doh-rollout.balrog-migration-done":true,"doh-rollout.home-region":"US","dom.forms.autocomplete.formautofill":true,"dom.allow_scripts_to_close_windows":true,"dom.disable_open_during_load":false,"dom.disable_window_flip":true,"dom.disable_window_move_resize":true,"dom.max_chrome_script_run_time":0,"dom.max_script_run_time":0,"dom.push.connection.enabled":false,"dom.send_after_paint_to_content":true,"extensions.checkCompatibility":false,"extensions.lastAppVersion":"93.0a1","fission.autostart":true,"general.useragent.updates.enabled":false,"gfx.crash-guard.wmfvpxvideo.deviceID":"0x193a","gfx.crash-guard.status.wmfvpxvideo":2,"gfx.crash-guard.wmfvpxvideo.appVersion":"93.0a1","gfx.crash-guard.wmfvpxvideo.driverVersion":"22.20.16.4815","gfx.webrender.all.qualified":false,"idle.lastDailyNotification":-1,"layers.d3d11.enable-blacklist":false,"media.gmp.storage.version.observed":1,"media.allowed-to-play.enabled":true,"media.autoplay.ask-permission":false,"media.autoplay.block-webaudio":false,"media.autoplay.blocking_policy":0,"media.autoplay.default":0,"media.block-autoplay-until-in-foreground":false,"media.capturestream_hints.enabled":true,"media.geckoview.autoplay.request":false,"media.gmp-manager.updateEnabled":false,"media.gmp-manager.url":"http://127.0.0.1/gmpmanager-dummy/update.xml","media.hardware-video-decoding.failed":false,"media.libavcodec.allow-obsolete":true,"media.navigator.enabled":true,"media.navigator.permission.disabled":true,"media.peerconnection.enabled":true,"media.peerconnection.mtransport_process":false,"network.trr.blocklist_cleanup_done":true,"network.http.speculative-parallel-limit":0,"network.process.enabled":false,"places.database.lastMaintenance":2147483647,"plugin.state.flash":0,"plugins.flashBlock.enabled":false,"privacy.sanitize.pending":"[{\"id\":\"newtab-container\",\"itemsToClear\":[],\"options\":{}}]","privacy.reduceTimerPrecision":false,"privacy.trackingprotection.annotate_channels":false,"privacy.trackingprotection.enabled":false,"privacy.trackingprotection.introURL":"http://127.0.0.1/trackingprotection/tour","privacy.trackingprotection.pbmode.enabled":false,"security.certerrors.mitm.priming.enabled":false,"security.enable_java":false,"security.fileuri.strict_origin_policy":false,"security.sandbox.content.tempDirSuffix":"{ac4d6335-d1ad-4e10-8c13-e7a1270d4441}","security.turn_off_all_security_so_that_viruses_can_take_over_this_computer":true,"services.sync.engine.addresses.available":true},"lockedPreferences":{"fission.autostart.session":true},"printingPreferences":{},"media":{"currentAudioBackend":"wasapi","currentMaxAudioChannels":2,"currentPreferredSampleRate":48000,"audioOutputDevices":[{"name":"Line 1 (Virtual Audio Cable)","groupId":"ROOT\\{83ED7F0E-2028-4956-B0B4-39C76FDAEF1D}\\0000","vendor":"","type":2,"state":2,"preferred":7,"supportedFormat":4112,"defaultFormat":4096,"maxChannels":2,"defaultRate":48000,"maxRate":48000,"minRate":48000,"maxLatency":480,"minLatency":144}],"audioInputDevices":[{"name":"Mic 1 (Virtual Audio Cable)","groupId":"ROOT\\{83ED7F0E-2028-4956-B0B4-39C76FDAEF1D}\\0000","vendor":"","type":1,"state":1,"preferred":0,"supportedFormat":4112,"defaultFormat":4096,"maxChannels":0,"defaultRate":0,"maxRate":0,"minRate":0,"maxLatency":0,"minLatency":0},{"name":"Line 1 (Virtual Audio Cable)","groupId":"ROOT\\{83ED7F0E-2028-4956-B0B4-39C76FDAEF1D}\\0000","vendor":"","type":1,"state":2,"preferred":7,"supportedFormat":4112,"defaultFormat":4096,"maxChannels":2,"defaultRate":48000,"maxRate":48000,"minRate":48000,"maxLatency":480,"minLatency":144},{"name":"S/PDIF 1 (Virtual Audio Cable)","groupId":"ROOT\\{83ED7F0E-2028-4956-B0B4-39C76FDAEF1D}\\0000","vendor":"","type":1,"state":1,"preferred":0,"supportedFormat":4112,"defaultFormat":4096,"maxChannels":0,"defaultRate":0,"maxRate":0,"minRate":0,"maxLatency":0,"minLatency":0}]},"accessibility":{"isActive":false,"forceDisabled":0,"handlerUsed":false,"instantiator":""},"startupCache":{"DiskCachePath":"C:\\Users\\task_1630758425\\AppData\\Local\\Temp\\tmpl0pdj55t\\profile\\startupCache\\startupCache.8.little","IgnoreDiskCache":false,"FoundDiskCacheOnInit":false,"WroteToDiskCache":false},"libraryVersions":{"NSPR":{"minVersion":"4.32","version":"4.32"},"NSS":{"minVersion":"3.70","version":"3.70"},"NSSUTIL":{"minVersion":"3.70","version":"3.70"},"NSSSSL":{"minVersion":"3.70","version":"3.70"},"NSSSMIME":{"minVersion":"3.70","version":"3.70"}},"userJS":{"exists":true},"intl":{"localeService":{"requested":["en-US"],"available":["en-US"],"supported":["en-US"],"regionalPrefs":["en-US"],"defaultLocale":"en-US"},"osPrefs":{"systemLocales":["en-US"],"regionalPrefsLocales":["en-US"]}},"crashes":{"submitted":[],"pending":0},"sandbox":{"contentSandboxLevel":6,"effectiveContentSandboxLevel":6,"contentWin32kLockdownState":"Win32k Lockdown disabled -- Preference not set"},"remoteAgent":{"listening":false,"url":""},"graphics":{"numTotalWindows":1,"numAcceleratedWindows":1,"windowLayerManagerType":"WebRender","windowLayerManagerRemote":true,"windowUsingAdvancedLayers":false,"adapterDescription":"Intel(R) Iris(R) Pro Graphics P580","adapterVendorID":"0x8086","adapterDeviceID":"0x193a","adapterSubsysID":"18a9103c","adapterRAM":0,"adapterDrivers":"igdumdim64 igd10iumd64 igd10iumd64 igd12umd64 igdumdim32 igd10iumd32 igd10iumd32 igd12umd32","driverVendor":"","driverVersion":"22.20.16.4815","driverDate":"9-26-2017","adapterDescription2":"","adapterVendorID2":"","adapterDeviceID2":"","adapterSubsysID2":"","adapterRAM2":0,"adapterDrivers2":"","driverVendor2":"","driverVersion2":"","driverDate2":"","isGPU2Active":false,"direct2DEnabled":true,"directWriteEnabled":true,"directWriteVersion":"10.0.17134.1","clearTypeParameters":"Gamma: 1.8 Pixel Structure: RGB ClearType Level: 100 Enhanced Contrast: 50 ","usesTiling":false,"targetFrameRate":60,"webgl1Renderer":"Google Inc. -- ANGLE (Intel(R) Iris(R) Pro Graphics P580 Direct3D11 vs_5_0 ps_5_0)","webgl1Version":"OpenGL ES 2.0.0 (ANGLE 2.1.14226 git hash: 31a43497be09)","webgl1DriverExtensions":"GL_ANGLE_base_vertex_base_instance GL_ANGLE_client_arrays GL_ANGLE_depth_texture GL_ANGLE_explicit_context GL_ANGLE_explicit_context_gles1 GL_ANGLE_framebuffer_blit GL_ANGLE_framebuffer_multisample GL_ANGLE_get_tex_level_parameter GL_ANGLE_instanced_arrays GL_ANGLE_lossy_etc_decode GL_ANGLE_memory_size GL_ANGLE_multi_draw GL_ANGLE_multiview_multisample GL_ANGLE_pack_reverse_row_order GL_ANGLE_program_cache_control GL_ANGLE_provoking_vertex GL_ANGLE_request_extension GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ANGLE_texture_usage GL_ANGLE_translated_shader_source GL_CHROMIUM_bind_generates_resource GL_CHROMIUM_bind_uniform_location GL_CHROMIUM_color_buffer_float_rgb GL_CHROMIUM_color_buffer_float_rgba GL_CHROMIUM_copy_compressed_texture GL_CHROMIUM_copy_texture GL_CHROMIUM_lose_context GL_CHROMIUM_sync_query GL_EXT_EGL_image_external_wrap_modes GL_EXT_blend_func_extended GL_EXT_blend_minmax GL_EXT_color_buffer_half_float GL_EXT_debug_marker GL_EXT_discard_framebuffer GL_EXT_disjoint_timer_query GL_EXT_draw_buffers GL_EXT_draw_elements_base_vertex GL_EXT_float_blend GL_EXT_frag_depth GL_EXT_instanced_arrays GL_EXT_map_buffer_range GL_EXT_multisampled_render_to_texture GL_EXT_occlusion_query_boolean GL_EXT_read_format_bgra GL_EXT_robustness GL_EXT_sRGB GL_EXT_shader_texture_lod GL_EXT_texture_compression_bptc GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc_srgb GL_EXT_texture_filter_anisotropic GL_EXT_texture_format_BGRA8888 GL_EXT_texture_rg GL_EXT_texture_storage GL_EXT_unpack_subimage GL_KHR_debug GL_KHR_no_error GL_KHR_parallel_shader_compile GL_KHR_robust_buffer_access_behavior GL_NV_EGL_stream_consumer_external GL_NV_fence GL_NV_pack_subimage GL_NV_pixel_buffer_object GL_OES_EGL_image GL_OES_EGL_image_external GL_OES_compressed_EAC_R11_signed_texture GL_OES_compressed_EAC_R11_unsigned_texture GL_OES_compressed_EAC_RG11_signed_texture GL_OES_compressed_EAC_RG11_unsigned_texture GL_OES_compressed_ETC2_RGB8_texture GL_OES_compressed_ETC2_RGBA8_texture GL_OES_compressed_ETC2_punchthroughA_RGBA8_texture GL_OES_compressed_ETC2_punchthroughA_sRGB8_alpha_texture GL_OES_compressed_ETC2_sRGB8_alpha8_texture GL_OES_compressed_ETC2_sRGB8_texture GL_OES_depth24 GL_OES_depth32 GL_OES_draw_elements_base_vertex GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_get_program_binary GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_surfaceless_context GL_OES_texture_border_clamp GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_texture_stencil8 GL_OES_vertex_array_object GL_WEBGL_video_texture ","webgl1Extensions":"ANGLE_instanced_arrays EXT_blend_minmax EXT_color_buffer_half_float EXT_float_blend EXT_frag_depth EXT_shader_texture_lod EXT_sRGB EXT_texture_compression_bptc EXT_texture_compression_rgtc EXT_texture_filter_anisotropic MOZ_debug OES_element_index_uint OES_fbo_render_mipmap OES_standard_derivatives OES_texture_float OES_texture_float_linear OES_texture_half_float OES_texture_half_float_linear OES_vertex_array_object WEBGL_color_buffer_float WEBGL_compressed_texture_s3tc WEBGL_compressed_texture_s3tc_srgb WEBGL_debug_renderer_info WEBGL_debug_shaders WEBGL_depth_texture WEBGL_draw_buffers WEBGL_lose_context","webgl1WSIInfo":"EGL_VENDOR: Google Inc. (adapter LUID: 00000000000063d0)\nEGL_VERSION: 1.5 (ANGLE 2.1.14226 git hash: 31a43497be09)\nEGL_EXTENSIONS: EGL_EXT_create_context_robustness EGL_ANGLE_d3d_share_handle_client_buffer EGL_ANGLE_d3d_texture_client_buffer EGL_ANGLE_surface_d3d_texture_2d_share_handle EGL_ANGLE_query_surface_pointer EGL_ANGLE_window_fixed_size EGL_ANGLE_keyed_mutex EGL_ANGLE_surface_orientation EGL_ANGLE_direct_composition EGL_NV_post_sub_buffer EGL_KHR_create_context EGL_EXT_device_query EGL_KHR_image EGL_KHR_image_base EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_gl_renderbuffer_image EGL_KHR_get_all_proc_addresses EGL_KHR_stream EGL_KHR_stream_consumer_gltexture EGL_NV_stream_consumer_gltexture_yuv EGL_ANGLE_flexible_surface_compatibility EGL_ANGLE_stream_producer_d3d_texture EGL_ANGLE_create_context_webgl_compatibility EGL_CHROMIUM_create_context_bind_generates_resource EGL_CHROMIUM_sync_control EGL_EXT_pixel_format_float EGL_KHR_surfaceless_context EGL_ANGLE_display_texture_share_group EGL_ANGLE_display_semaphore_share_group EGL_ANGLE_create_context_client_arrays EGL_ANGLE_program_cache_control EGL_ANGLE_robust_resource_initialization EGL_ANGLE_create_context_extensions_enabled EGL_ANDROID_blob_cache EGL_ANDROID_recordable EGL_ANGLE_image_d3d11_texture EGL_ANGLE_create_context_backwards_compatible EGL_KHR_create_context_no_error EGL_KHR_reusable_sync \nEGL_EXTENSIONS(nullptr): EGL_EXT_client_extensions EGL_EXT_platform_base EGL_EXT_platform_device EGL_ANGLE_platform_angle EGL_ANGLE_platform_angle_d3d EGL_ANGLE_platform_angle_d3d11on12 EGL_ANGLE_device_creation EGL_ANGLE_device_creation_d3d11 EGL_ANGLE_experimental_present_path EGL_KHR_client_get_all_proc_addresses EGL_KHR_debug EGL_ANGLE_explicit_context EGL_ANGLE_feature_control \nIsWebglOutOfProcessEnabled: 1","webgl2Renderer":"Google Inc. -- ANGLE (Intel(R) Iris(R) Pro Graphics P580 Direct3D11 vs_5_0 ps_5_0)","webgl2Version":"OpenGL ES 3.0.0 (ANGLE 2.1.14226 git hash: 31a43497be09)","webgl2DriverExtensions":"GL_ANGLE_base_vertex_base_instance GL_ANGLE_client_arrays GL_ANGLE_copy_texture_3d GL_ANGLE_depth_texture GL_ANGLE_explicit_context GL_ANGLE_explicit_context_gles1 GL_ANGLE_framebuffer_blit GL_ANGLE_framebuffer_multisample GL_ANGLE_get_tex_level_parameter GL_ANGLE_instanced_arrays GL_ANGLE_lossy_etc_decode GL_ANGLE_memory_size GL_ANGLE_multi_draw GL_ANGLE_multiview_multisample GL_ANGLE_pack_reverse_row_order GL_ANGLE_program_cache_control GL_ANGLE_provoking_vertex GL_ANGLE_request_extension GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ANGLE_texture_multisample GL_ANGLE_texture_usage GL_ANGLE_translated_shader_source GL_CHROMIUM_bind_generates_resource GL_CHROMIUM_bind_uniform_location GL_CHROMIUM_copy_compressed_texture GL_CHROMIUM_copy_texture GL_CHROMIUM_lose_context GL_CHROMIUM_sync_query GL_EXT_EGL_image_external_wrap_modes GL_EXT_blend_func_extended GL_EXT_blend_minmax GL_EXT_color_buffer_float GL_EXT_color_buffer_half_float GL_EXT_debug_marker GL_EXT_discard_framebuffer GL_EXT_disjoint_timer_query GL_EXT_draw_buffers GL_EXT_draw_buffers_indexed GL_EXT_draw_elements_base_vertex GL_EXT_float_blend GL_EXT_frag_depth GL_EXT_instanced_arrays GL_EXT_map_buffer_range GL_EXT_multisampled_render_to_texture GL_EXT_occlusion_query_boolean GL_EXT_read_format_bgra GL_EXT_robustness GL_EXT_sRGB GL_EXT_shader_texture_lod GL_EXT_texture_compression_bptc GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc_srgb GL_EXT_texture_filter_anisotropic GL_EXT_texture_format_BGRA8888 GL_EXT_texture_norm16 GL_EXT_texture_rg GL_EXT_texture_storage GL_EXT_unpack_subimage GL_KHR_debug GL_KHR_no_error GL_KHR_parallel_shader_compile GL_KHR_robust_buffer_access_behavior GL_NV_EGL_stream_consumer_external GL_NV_fence GL_NV_pack_subimage GL_NV_pixel_buffer_object GL_OES_EGL_image GL_OES_EGL_image_external GL_OES_EGL_image_external_essl3 GL_OES_compressed_EAC_R11_signed_texture GL_OES_compressed_EAC_R11_unsigned_texture GL_OES_compressed_EAC_RG11_signed_texture GL_OES_compressed_EAC_RG11_unsigned_texture GL_OES_compressed_ETC2_RGB8_texture GL_OES_compressed_ETC2_RGBA8_texture GL_OES_compressed_ETC2_punchthroughA_RGBA8_texture GL_OES_compressed_ETC2_punchthroughA_sRGB8_alpha_texture GL_OES_compressed_ETC2_sRGB8_alpha8_texture GL_OES_compressed_ETC2_sRGB8_texture GL_OES_depth24 GL_OES_depth32 GL_OES_draw_buffers_indexed GL_OES_draw_elements_base_vertex GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_get_program_binary GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_surfaceless_context GL_OES_texture_border_clamp GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_texture_stencil8 GL_OES_vertex_array_object GL_OVR_multiview GL_OVR_multiview2 GL_WEBGL_video_texture ","webgl2Extensions":"EXT_color_buffer_float EXT_float_blend EXT_texture_compression_bptc EXT_texture_compression_rgtc EXT_texture_filter_anisotropic MOZ_debug OES_texture_float_linear OVR_multiview2 WEBGL_compressed_texture_s3tc WEBGL_compressed_texture_s3tc_srgb WEBGL_debug_renderer_info WEBGL_debug_shaders WEBGL_lose_context","webgl2WSIInfo":"EGL_VENDOR: Google Inc. (adapter LUID: 00000000000063d0)\nEGL_VERSION: 1.5 (ANGLE 2.1.14226 git hash: 31a43497be09)\nEGL_EXTENSIONS: EGL_EXT_create_context_robustness EGL_ANGLE_d3d_share_handle_client_buffer EGL_ANGLE_d3d_texture_client_buffer EGL_ANGLE_surface_d3d_texture_2d_share_handle EGL_ANGLE_query_surface_pointer EGL_ANGLE_window_fixed_size EGL_ANGLE_keyed_mutex EGL_ANGLE_surface_orientation EGL_ANGLE_direct_composition EGL_NV_post_sub_buffer EGL_KHR_create_context EGL_EXT_device_query EGL_KHR_image EGL_KHR_image_base EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_gl_renderbuffer_image EGL_KHR_get_all_proc_addresses EGL_KHR_stream EGL_KHR_stream_consumer_gltexture EGL_NV_stream_consumer_gltexture_yuv EGL_ANGLE_flexible_surface_compatibility EGL_ANGLE_stream_producer_d3d_texture EGL_ANGLE_create_context_webgl_compatibility EGL_CHROMIUM_create_context_bind_generates_resource EGL_CHROMIUM_sync_control EGL_EXT_pixel_format_float EGL_KHR_surfaceless_context EGL_ANGLE_display_texture_share_group EGL_ANGLE_display_semaphore_share_group EGL_ANGLE_create_context_client_arrays EGL_ANGLE_program_cache_control EGL_ANGLE_robust_resource_initialization EGL_ANGLE_create_context_extensions_enabled EGL_ANDROID_blob_cache EGL_ANDROID_recordable EGL_ANGLE_image_d3d11_texture EGL_ANGLE_create_context_backwards_compatible EGL_KHR_create_context_no_error EGL_KHR_reusable_sync \nEGL_EXTENSIONS(nullptr): EGL_EXT_client_extensions EGL_EXT_platform_base EGL_EXT_platform_device EGL_ANGLE_platform_angle EGL_ANGLE_platform_angle_d3d EGL_ANGLE_platform_angle_d3d11on12 EGL_ANGLE_device_creation EGL_ANGLE_device_creation_d3d11 EGL_ANGLE_experimental_present_path EGL_KHR_client_get_all_proc_addresses EGL_KHR_debug EGL_ANGLE_explicit_context EGL_ANGLE_feature_control \nIsWebglOutOfProcessEnabled: 1","info":{"AzureCanvasBackend (UI Process)":"skia","AzureFallbackCanvasBackend (UI Process)":"skia","AzureContentBackend (UI Process)":"skia","AzureCanvasBackend":"direct2d 1.1","AzureContentBackend":"skia","ApzWheelInput":1,"ApzDragInput":1,"ApzKeyboardInput":1,"ApzAutoscrollInput":1,"ApzZoomingInput":1,"CMSOutputProfile":"AAAMSExpbm8CEAAAbW50clJHQiBYWVogB84AAgAJAAYAMQAAYWNzcE1TRlQAAAAASUVDIHNSR0IAAAAAAAAAAAAAAAAAAPbWAAEAAAAA0y1IUCAgAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAARY3BydAAAAVAAAAAzZGVzYwAAAYQAAABsd3RwdAAAAfAAAAAUYmtwdAAAAgQAAAAUclhZWgAAAhgAAAAUZ1hZWgAAAiwAAAAUYlhZWgAAAkAAAAAUZG1uZAAAAlQAAABwZG1kZAAAAsQAAACIdnVlZAAAA0wAAACGdmlldwAAA9QAAAAkbHVtaQAAA/gAAAAUbWVhcwAABAwAAAAkdGVjaAAABDAAAAAMclRSQwAABDwAAAgMZ1RSQwAABDwAAAgMYlRSQwAABDwAAAgMdGV4dAAAAABDb3B5cmlnaHQgKGMpIDE5OTggSGV3bGV0dC1QYWNrYXJkIENvbXBhbnkAAGRlc2MAAAAAAAAAEnNSR0IgSUVDNjE5NjYtMi4xAAAAAAAAAAAAAAASc1JHQiBJRUM2MTk2Ni0yLjEAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAFhZWiAAAAAAAADzUQABAAAAARbMWFlaIAAAAAAAAAAAAAAAAAAAAABYWVogAAAAAAAAb6IAADj1AAADkFhZWiAAAAAAAABimQAAt4UAABjaWFlaIAAAAAAAACSgAAAPhAAAts9kZXNjAAAAAAAAABZJRUMgaHR0cDovL3d3dy5pZWMuY2gAAAAAAAAAAAAAABZJRUMgaHR0cDovL3d3dy5pZWMuY2gAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAZGVzYwAAAAAAAAAuSUVDIDYxOTY2LTIuMSBEZWZhdWx0IFJHQiBjb2xvdXIgc3BhY2UgLSBzUkdCAAAAAAAAAAAAAAAuSUVDIDYxOTY2LTIuMSBEZWZhdWx0IFJHQiBjb2xvdXIgc3BhY2UgLSBzUkdCAAAAAAAAAAAAAAAAAAAAAAAAAAAAAGRlc2MAAAAAAAAALFJlZmVyZW5jZSBWaWV3aW5nIENvbmRpdGlvbiBpbiBJRUM2MTk2Ni0yLjEAAAAAAAAAAAAAACxSZWZlcmVuY2UgVmlld2luZyBDb25kaXRpb24gaW4gSUVDNjE5NjYtMi4xAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAB2aWV3AAAAAAATpP4AFF8uABDPFAAD7cwABBMLAANcngAAAAFYWVogAAAAAABMCVYAUAAAAFcf521lYXMAAAAAAAAAAQAAAAAAAAAAAAAAAAAAAAAAAAKPAAAAAnNpZyAAAAAAQ1JUIGN1cnYAAAAAAAAEAAAAAAUACgAPABQAGQAeACMAKAAtADIANwA7AEAARQBKAE8AVABZAF4AYwBoAG0AcgB3AHwAgQCGAIsAkACVAJoAnwCkAKkArgCyALcAvADBAMYAywDQANUA2wDgAOUA6wDwAPYA+wEBAQcBDQETARkBHwElASsBMgE4AT4BRQFMAVIBWQFgAWcBbgF1AXwBgwGLAZIBmgGhAakBsQG5AcEByQHRAdkB4QHpAfIB+gIDAgwCFAIdAiYCLwI4AkECSwJUAl0CZwJxAnoChAKOApgCogKsArYCwQLLAtUC4ALrAvUDAAMLAxYDIQMtAzgDQwNPA1oDZgNyA34DigOWA6IDrgO6A8cD0wPgA+wD+QQGBBMEIAQtBDsESARVBGMEcQR+BIwEmgSoBLYExATTBOEE8AT+BQ0FHAUrBToFSQVYBWcFdwWGBZYFpgW1BcUF1QXlBfYGBgYWBicGNwZIBlkGagZ7BowGnQavBsAG0QbjBvUHBwcZBysHPQdPB2EHdAeGB5kHrAe/B9IH5Qf4CAsIHwgyCEYIWghuCIIIlgiqCL4I0gjnCPsJEAklCToJTwlkCXkJjwmkCboJzwnlCfsKEQonCj0KVApqCoEKmAquCsUK3ArzCwsLIgs5C1ELaQuAC5gLsAvIC+EL+QwSDCoMQwxcDHUMjgynDMAM2QzzDQ0NJg1ADVoNdA2ODakNww3eDfgOEw4uDkkOZA5/DpsOtg7SDu4PCQ8lD0EPXg96D5YPsw/PD+wQCRAmEEMQYRB+EJsQuRDXEPURExExEU8RbRGMEaoRyRHoEgcSJhJFEmQShBKjEsMS4xMDEyMTQxNjE4MTpBPFE+UUBhQnFEkUahSLFK0UzhTwFRIVNBVWFXgVmxW9FeAWAxYmFkkWbBaPFrIW1hb6Fx0XQRdlF4kXrhfSF/cYGxhAGGUYihivGNUY+hkgGUUZaxmRGbcZ3RoEGioaURp3Gp4axRrsGxQbOxtjG4obshvaHAIcKhxSHHscoxzMHPUdHh1HHXAdmR3DHeweFh5AHmoelB6+HukfEx8+H2kflB+/H+ogFSBBIGwgmCDEIPAhHCFIIXUhoSHOIfsiJyJVIoIiryLdIwojOCNmI5QjwiPwJB8kTSR8JKsk2iUJJTglaCWXJccl9yYnJlcmhya3JugnGCdJJ3onqyfcKA0oPyhxKKIo1CkGKTgpaymdKdAqAio1KmgqmyrPKwIrNitpK50r0SwFLDksbiyiLNctDC1BLXYtqy3hLhYuTC6CLrcu7i8kL1ovkS/HL/4wNTBsMKQw2zESMUoxgjG6MfIyKjJjMpsy1DMNM0YzfzO4M/E0KzRlNJ402DUTNU01hzXCNf02NzZyNq426TckN2A3nDfXOBQ4UDiMOMg5BTlCOX85vDn5OjY6dDqyOu87LTtrO6o76DwnPGU8pDzjPSI9YT2hPeA+ID5gPqA+4D8hP2E/oj/iQCNAZECmQOdBKUFqQaxB7kIwQnJCtUL3QzpDfUPARANER0SKRM5FEkVVRZpF3kYiRmdGq0bwRzVHe0fASAVIS0iRSNdJHUljSalJ8Eo3Sn1KxEsMS1NLmkviTCpMcky6TQJNSk2TTdxOJU5uTrdPAE9JT5NP3VAnUHFQu1EGUVBRm1HmUjFSfFLHUxNTX1OqU/ZUQlSPVNtVKFV1VcJWD1ZcVqlW91dEV5JX4FgvWH1Yy1kaWWlZuFoHWlZaplr1W0VblVvlXDVchlzWXSddeF3JXhpebF69Xw9fYV+zYAVgV2CqYPxhT2GiYfViSWKcYvBjQ2OXY+tkQGSUZOllPWWSZedmPWaSZuhnPWeTZ+loP2iWaOxpQ2maafFqSGqfavdrT2una/9sV2yvbQhtYG25bhJua27Ebx5veG/RcCtwhnDgcTpxlXHwcktypnMBc11zuHQUdHB0zHUodYV14XY+dpt2+HdWd7N4EXhueMx5KnmJeed6RnqlewR7Y3vCfCF8gXzhfUF9oX4BfmJ+wn8jf4R/5YBHgKiBCoFrgc2CMIKSgvSDV4O6hB2EgITjhUeFq4YOhnKG14c7h5+IBIhpiM6JM4mZif6KZIrKizCLlov8jGOMyo0xjZiN/45mjs6PNo+ekAaQbpDWkT+RqJIRknqS45NNk7aUIJSKlPSVX5XJljSWn5cKl3WX4JhMmLiZJJmQmfyaaJrVm0Kbr5wcnImc951kndKeQJ6unx2fi5/6oGmg2KFHobaiJqKWowajdqPmpFakx6U4pammGqaLpv2nbqfgqFKoxKk3qamqHKqPqwKrdavprFys0K1ErbiuLa6hrxavi7AAsHWw6rFgsdayS7LCszizrrQltJy1E7WKtgG2ebbwt2i34LhZuNG5SrnCuju6tbsuu6e8IbybvRW9j74KvoS+/796v/XAcMDswWfB48JfwtvDWMPUxFHEzsVLxcjGRsbDx0HHv8g9yLzJOsm5yjjKt8s2y7bMNcy1zTXNtc42zrbPN8+40DnQutE80b7SP9LB00TTxtRJ1MvVTtXR1lXW2Ndc1+DYZNjo2WzZ8dp22vvbgNwF3IrdEN2W3hzeot8p36/gNuC94UThzOJT4tvjY+Pr5HPk/OWE5g3mlucf56noMui86Ubp0Opb6uXrcOv77IbtEe2c7ijutO9A78zwWPDl8XLx//KM8xnzp/Q09ML1UPXe9m32+/eK+Bn4qPk4+cf6V/rn+3f8B/yY/Sn9uv5L/tz/bf//","DisplayCount":1,"Display0":"1024x768@60Hz : Intel(R) Iris(R) Pro Graphics P580","HardwareStretching":"both=0 window-only=0 full-screen-only=0 none=1 error=0"},"featureLog":{"features":[{"name":"HW_COMPOSITING","description":"Compositing","status":"available","log":[{"type":"default","status":"available"}]},{"name":"D3D11_COMPOSITING","description":"Direct3D11 Compositing","status":"available","log":[{"type":"default","status":"available"}]},{"name":"DIRECT2D","description":"Direct2D","status":"available","log":[{"type":"default","status":"available"}]},{"name":"D3D11_HW_ANGLE","description":"Direct3D11 hardware ANGLE","status":"available","log":[{"type":"default","status":"available"}]},{"name":"GPU_PROCESS","description":"GPU Process","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER","description":"WebRender","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_QUALIFIED","description":"WebRender qualified","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_COMPOSITOR","description":"WebRender native compositor","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_PARTIAL","description":"WebRender partial present","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_SHADER_CACHE","description":"WebRender shader disk cache","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_OPTIMIZED_SHADERS","description":"WebRender optimized shaders","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_ANGLE","description":"WebRender ANGLE","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_DCOMP_PRESENT","description":"WebRender DirectComposition","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBRENDER_SOFTWARE","description":"WebRender software fallback","status":"available","log":[{"type":"default","status":"available"}]},{"name":"WEBGPU","description":"WebGPU","status":"disabled","log":[{"type":"default","status":"disabled","message":"Disabled by default"}]}],"fallbacks":[]},"crashGuards":[]},"experimentalFeatures":[["experimental-features-abouthome-startup-cache","browser.startup.homepage.abouthome_cache.enabled",true],["experimental-features-cookie-samesite-lax-by-default2","network.cookie.sameSite.laxByDefault",true],["experimental-features-cookie-samesite-none-requires-secure2","network.cookie.sameSite.noneRequiresSecure",true],["experimental-features-cookie-samesite-schemeful","network.cookie.sameSite.schemeful",true],["experimental-features-css-constructable-stylesheets","layout.css.constructable-stylesheets.enabled",false],["experimental-features-css-masonry2","layout.css.grid-template-masonry-value.enabled",true],["experimental-features-devtools-compatibility-panel","devtools.inspector.compatibility.enabled",true],["experimental-features-devtools-execution-context-selector","devtools.webconsole.input.context",true],["experimental-features-devtools-serviceworker-debugger-support","devtools.debugger.features.windowless-service-workers",false],["experimental-features-fission","fission.autostart",true],["experimental-features-media-jxl","image.jxl.enabled",false],["experimental-features-multi-pip","media.videocontrols.picture-in-picture.allow-multiple",true],["experimental-features-ime-search","browser.urlbar.keepPanelOpenDuringImeComposition",false],["experimental-features-web-api-inputmode","dom.forms.inputmode",true],["experimental-features-web-gpu2","dom.webgpu.enabled",false],["experimental-features-webrtc-global-mute-toggles","privacy.webrtc.globalMuteToggles",false],["experimental-features-win32k-lockdown","security.sandbox.content.win32k-disable",false]],"addons":[{"name":"PageLoader extension","type":"extension","version":"1.1","isActive":true,"id":"pageloader@mozilla.org"},{"name":"Tabswitch Talos Test","type":"extension","version":"0.1","isActive":true,"id":"tabswitch-talos@mozilla.org"},{"name":"Talos Powers","type":"extension","version":"1.1","isActive":true,"id":"talos-powers@mozilla.org"}],"features":[{"name":"DoH Roll-Out","version":"2.0.0","id":"doh-rollout@mozilla.org"},{"name":"Firefox Screenshots","version":"39.0.1","id":"screenshots@mozilla.org"},{"name":"Form Autofill","version":"1.0.1","id":"formautofill@mozilla.org"},{"name":"Picture-In-Picture","version":"1.0.0","id":"pictureinpicture@mozilla.org"},{"name":"Web Compatibility Interventions","version":"26.0.0","id":"webcompat@mozilla.org"},{"name":"WebCompat Reporter","version":"1.4.2","id":"webcompat-reporter@mozilla.org"}],"normandy":{"addonStudies":[],"prefRollouts":[],"prefStudies":[],"nimbusExperiments":[],"remoteConfigs":[]}}
[task 2021-09-04T22:56:45.626Z] 22:56:45    ERROR -  JavaScript error: moz-extension://6dc66039-f733-4b66-bbb9-b85cc3b404d8/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2021-09-04T22:56:45.630Z] 22:56:45    ERROR -  JavaScript error: moz-extension://6dc66039-f733-4b66-bbb9-b85cc3b404d8/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2021-09-04T22:56:45.633Z] 22:56:45     INFO -  JavaScript error: resource:///modules/Interactions.jsm, line 201: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-09-04T22:56:45.640Z] 22:56:45    ERROR -  JavaScript error: moz-extension://6dc66039-f733-4b66-bbb9-b85cc3b404d8/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2021-09-04T22:56:45.673Z] 22:56:45     INFO -  [Parent 7640, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-09-04T22:56:46.195Z] 22:56:46     INFO -  TEST-INFO | 5336: exit 0
<...>
[task 2021-09-04T23:03:08.407Z] 23:03:08     INFO -  TEST-INFO | 3336: exit 0
[task 2021-09-04T23:03:09.953Z] 23:03:09     INFO -  mozcrash checking C:\Users\task_1630758425\AppData\Local\Temp\tmpl0pdj55t\profile\minidumps for minidumps...
[task 2021-09-04T23:03:09.954Z] 23:03:09     INFO -  TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398'
[task 2021-09-04T23:03:09.954Z] 23:03:09    ERROR -  Traceback (most recent call last):
[task 2021-09-04T23:03:09.954Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\run_tests.py", line 336, in run_tests
[task 2021-09-04T23:03:09.954Z] 23:03:09     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-09-04T23:03:09.955Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-09-04T23:03:09.955Z] 23:03:09     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-09-04T23:03:09.955Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\ttest.py", line 274, in _runTest
[task 2021-09-04T23:03:09.956Z] 23:03:09     INFO -      counter_management.results() if counter_management else None
[task 2021-09-04T23:03:09.956Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\results.py", line 97, in add
[task 2021-09-04T23:03:09.956Z] 23:03:09     INFO -      results = browserLog.results()
[task 2021-09-04T23:03:09.957Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\results.py", line 424, in results
[task 2021-09-04T23:03:09.957Z] 23:03:09     INFO -      self.browser_results, format_pagename=self.format_pagename
[task 2021-09-04T23:03:09.957Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\results.py", line 262, in __init__
[task 2021-09-04T23:03:09.958Z] 23:03:09     INFO -      result["runs"] = [float(i) for i in r[2:]]
[task 2021-09-04T23:03:09.958Z] 23:03:09     INFO -    File "C:\Users\task_1630758425\build\tests\talos\talos\results.py", line 262, in <listcomp>
[task 2021-09-04T23:03:09.958Z] 23:03:09     INFO -      result["runs"] = [float(i) for i in r[2:]]
[task 2021-09-04T23:03:09.958Z] 23:03:09     INFO -  ValueError: could not convert string to float: '8.36334[2196622398'
[task 2021-09-04T23:03:09.959Z] 23:03:09     INFO -  TEST-INFO took 387606ms
[task 2021-09-04T23:03:09.959Z] 23:03:09     INFO -  SUITE-END | took 387s
[task 2021-09-04T23:03:10.080Z] 23:03:10    ERROR - Return code: 2
[task 2021-09-04T23:03:10.081Z] 23:03:10  WARNING - setting return code to 2
[task 2021-09-04T23:03:10.081Z] 23:03:10    ERROR - # TBPL FAILURE #
[task 2021-09-04T23:03:10.081Z] 23:03:10     INFO - Running post-action listener: _package_coverage_data
[task 2021-09-04T23:03:10.081Z] 23:03:10     INFO - Running post-action listener: _resource_record_post_action
[task 2021-09-04T23:03:10.081Z] 23:03:10     INFO - Running post-action listener: process_java_coverage_data
[task 2021-09-04T23:03:10.081Z] 23:03:10     INFO - [mozharness: 2021-09-04 23:03:10.081019Z] Finished run-tests step (success)
[task 2021-09-04T23:03:10.081Z] 23:03:10     INFO - Running post-run listener: _resource_record_post_run
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - Total resource usage - Wall time: 389s; CPU: 27%; Read bytes: 125566464; Write bytes: 1392738816; Read time: 2; Write time: 8
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: CPU usage<br/>27.0%
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: I/O read bytes / time<br/>125,566,464 / 2
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: I/O write bytes / time<br/>1,392,738,816 / 8
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: CPU idle<br/>2,275.1 (73.0%)
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: CPU system<br/>281.1 (9.0%)
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - TinderboxPrint: CPU user<br/>558.7 (17.9%)
[task 2021-09-04T23:03:10.186Z] 23:03:10     INFO - install - Wall time: 2s; CPU: 13%; Read bytes: 65536; Write bytes: 15175680; Read time: 0; Write time: 0
[task 2021-09-04T23:03:10.190Z] 23:03:10     INFO - run-tests - Wall time: 388s; CPU: 27%; Read bytes: 124992000; Write bytes: 1257853440; Read time: 2; Write time: 7
[task 2021-09-04T23:03:10.297Z] 23:03:10  WARNING - returning nonzero exit status 2
[taskcluster 2021-09-04T23:03:10.340Z]    Exit Code: 2
[taskcluster 2021-09-04T23:03:10.340Z]    User Time: 0s
[taskcluster 2021-09-04T23:03:10.340Z]  Kernel Time: 0s
[taskcluster 2021-09-04T23:03:10.340Z]    Wall Time: 8m9.5956424s
[taskcluster 2021-09-04T23:03:10.340Z]       Result: FAILED
[taskcluster 2021-09-04T23:03:10.340Z] === Task Finished ===
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent [Tier 2] TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398' → Intermittent TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398'
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Deleted

Hi Cristian, can you back fill the intermittent failure please?

Flags: needinfo?(ctuns)

This became frequent with the changes in Bug 1772350 here. Jens, could you have a look?

Flags: needinfo?(ctuns) → needinfo?(jstutte)

(In reply to Cosmin Sabou [:CosminS] from comment #29)

This became frequent with the changes in Bug 1772350 here. Jens, could you have a look?

I cannot really see any reason why. The patch in question just added some (admittedly slightly verbose) log messages during process shutdown. Let's observe another day or two if this was just a random coincidence. If not, the only possible explanation would be that we have a fragile race uncovered by some (otherwise unrelated) timing changes (spending more time in logging).

A successful run:

[task 2022-06-14T22:38:58.173Z] 22:38:58     INFO -  TEST-START | tabswitch
[task 2022-06-14T22:38:58.174Z] 22:38:58     INFO -  Initialising browser for tabswitch test...
[task 2022-06-14T22:38:58.174Z] 22:38:58     INFO -  Cloning profile located at C:\Users\task_1655232329\build\tests\talos\talos\base_profile
[task 2022-06-14T22:38:58.176Z] 22:38:58     INFO -  Merging profile: C:\Users\task_1655232329\build\tests\talos\talos\profile_data\base
[task 2022-06-14T22:38:58.182Z] 22:38:58     INFO -  Merging profile: C:\Users\task_1655232329\build\tests\talos\talos\profile_data\common
[task 2022-06-14T22:38:58.186Z] 22:38:58     INFO -  Merging profile: C:\Users\task_1655232329\build\tests\talos\talos\profile_data\perf
[task 2022-06-14T22:38:58.191Z] 22:38:58     INFO -  Installing Add-ons:
[task 2022-06-14T22:38:58.192Z] 22:38:58     INFO -  ['C:\\Users\\task_1655232329\\build\\tests\\talos\\talos/talos-powers', 'C:\\Users\\task_1655232329\\build\\tests\\talos\\talos/tests/tabswitch', 'C:\\Users\\task_1655232329\\build\\tests\\talos\\talos/pageloader']
[task 2022-06-14T22:38:58.221Z] 22:38:58     INFO -  Installing Webextensions:
[task 2022-06-14T22:38:58.222Z] 22:38:58     INFO -  Application command: C:\Users\task_1655232329\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49946/getInfo.html --wait-for-browser -profile C:\Users\task_1655232329\AppData\Local\Temp\tmpy6lgt8iz\profile
[task 2022-06-14T22:38:58.228Z] 22:38:58     INFO -  TEST-INFO | started process 9420 (C:\Users\task_1655232329\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49946/getInfo.html)
[task 2022-06-14T22:38:59.496Z] 22:38:59     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1655232329\\AppData\\Local\\Temp\\tmpy6lgt8iz\\profile\\search.json.mozlz4", (void 0)))
[task 2022-06-14T22:38:59.519Z] 22:38:59     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e5ccc500 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:38:59.641Z] 22:38:59     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e5ccc500 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:38:59.641Z] 22:38:59     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e5ccc500 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:38:59.655Z] 22:38:59     INFO -  [Parent 5608, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-06-14T22:38:59.741Z] 22:38:59     INFO -  __metrics
[task 2022-06-14T22:38:59.741Z] 22:38:59     INFO -  	Screen width/height: 1920/1080
[task 2022-06-14T22:38:59.741Z] 22:38:59     INFO -  	colorDepth: 24
[task 2022-06-14T22:38:59.741Z] 22:38:59     INFO -  	Browser inner width/height: 1284/920
[task 2022-06-14T22:38:59.742Z] 22:38:59     INFO -  __metrics
[task 2022-06-14T22:38:59.858Z] 22:38:59     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 708: WebGL context was lost.
[task 2022-06-14T22:38:59.875Z] 22:38:59     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 708: WebGL context was lost.
[task 2022-06-14T22:39:00.210Z] 22:39:00     INFO -  about:support	{...}
[task 2022-06-14T22:39:00.578Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e799f100 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:39:00.579Z] 22:39:00    ERROR -  JavaScript error: moz-extension://e255bf05-9555-4555-b486-6f7dc0e7bde3/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2022-06-14T22:39:00.580Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e7a17300 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:39:00.582Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e799f100 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:39:00.583Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e799f100 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:39:00.585Z] 22:39:00    ERROR -  JavaScript error: moz-extension://e255bf05-9555-4555-b486-6f7dc0e7bde3/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2022-06-14T22:39:00.587Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e7a17300 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:39:00.587Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0e7a17300 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:39:00.608Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea21f500 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3598
[task 2022-06-14T22:39:00.609Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea21f500 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:39:00.609Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220e00 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3598
[task 2022-06-14T22:39:00.611Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220e00 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:39:00.611Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220400 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3598
[task 2022-06-14T22:39:00.612Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220400 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-06-14T22:39:00.616Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea21f500 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:39:00.617Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea21f500 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:39:00.619Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220e00 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:39:00.619Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220e00 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:39:00.621Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220400 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1838
[task 2022-06-14T22:39:00.623Z] 22:39:00     INFO -  [Parent 5608, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-06-14T22:39:00.624Z] 22:39:00     INFO -  [Parent 5608, Main Thread] WARNING: ContentParent: id=1b0ea220400 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1845
[task 2022-06-14T22:39:00.679Z] 22:39:00     INFO -  *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: recipe-client-addon-run
[task 2022-06-14T22:39:00.692Z] 22:39:00     INFO -  JavaScript error: resource:///modules/Discovery.jsm, line 106: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2022-06-14T22:39:01.163Z] 22:39:01     INFO -  TEST-INFO | 9420: exit 0

A failing run:

[task 2022-06-16T00:31:40.438Z] 00:31:40     INFO -  TEST-START | tabswitch
[task 2022-06-16T00:31:40.439Z] 00:31:40     INFO -  Initialising browser for tabswitch test...
[task 2022-06-16T00:31:40.439Z] 00:31:40     INFO -  Cloning profile located at C:\Users\task_1655338904\build\tests\talos\talos\base_profile
[task 2022-06-16T00:31:40.441Z] 00:31:40     INFO -  Merging profile: C:\Users\task_1655338904\build\tests\talos\talos\profile_data\base
[task 2022-06-16T00:31:40.447Z] 00:31:40     INFO -  Merging profile: C:\Users\task_1655338904\build\tests\talos\talos\profile_data\common
[task 2022-06-16T00:31:40.452Z] 00:31:40     INFO -  Merging profile: C:\Users\task_1655338904\build\tests\talos\talos\profile_data\perf
[task 2022-06-16T00:31:40.457Z] 00:31:40     INFO -  Installing Add-ons:
[task 2022-06-16T00:31:40.457Z] 00:31:40     INFO -  ['C:\\Users\\task_1655338904\\build\\tests\\talos\\talos/talos-powers', 'C:\\Users\\task_1655338904\\build\\tests\\talos\\talos/tests/tabswitch', 'C:\\Users\\task_1655338904\\build\\tests\\talos\\talos/pageloader']
[task 2022-06-16T00:31:40.489Z] 00:31:40     INFO -  Installing Webextensions:
[task 2022-06-16T00:31:40.490Z] 00:31:40     INFO -  Application command: C:\Users\task_1655338904\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49876/getInfo.html --wait-for-browser -profile C:\Users\task_1655338904\AppData\Local\Temp\tmp3at8w7mq\profile
[task 2022-06-16T00:31:40.497Z] 00:31:40     INFO -  TEST-INFO | started process 6516 (C:\Users\task_1655338904\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:49876/getInfo.html)
[task 2022-06-16T00:31:41.787Z] 00:31:41     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1655338904\\AppData\\Local\\Temp\\tmp3at8w7mq\\profile\\search.json.mozlz4", (void 0)))
[task 2022-06-16T00:31:41.810Z] 00:31:41     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d3e38ee00 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:41.911Z] 00:31:41     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d3e38ee00 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:41.912Z] 00:31:41     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d3e38ee00 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:41.924Z] 00:31:41     INFO -  [Parent 4244, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-06-16T00:31:42.041Z] 00:31:42     INFO -  __metrics
[task 2022-06-16T00:31:42.042Z] 00:31:42     INFO -  	Screen width/height: 1920/1080
[task 2022-06-16T00:31:42.042Z] 00:31:42     INFO -  	colorDepth: 24
[task 2022-06-16T00:31:42.042Z] 00:31:42     INFO -  	Browser inner width/height: 1284/920
[task 2022-06-16T00:31:42.043Z] 00:31:42     INFO -  __metrics
[task 2022-06-16T00:31:42.158Z] 00:31:42     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 708: WebGL context was lost.
[task 2022-06-16T00:31:42.173Z] 00:31:42     INFO -  JavaScript warning: resource://gre/modules/Troubleshoot.jsm, line 708: WebGL context was lost.
[task 2022-06-16T00:31:42.501Z] 00:31:42     INFO -  about:support	{...}
[task 2022-06-16T00:31:42.925Z] 00:31:42    ERROR -  JavaScript error: moz-extension://41562a06-2af9-4e00-a5cf-7db54038d35d/content/tabswitch-content-process.js, line 17: TypeError: can't access property "getURL", extensionPolicy is null
[task 2022-06-16T00:31:42.932Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d4012e000 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:42.936Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d40108600 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:42.937Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d4012e000 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:42.938Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d4012e000 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:42.939Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d40108600 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:42.940Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d40108600 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:42.967Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a37c00 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3600
[task 2022-06-16T00:31:42.968Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a37c00 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:42.968Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a34000 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3600
[task 2022-06-16T00:31:42.969Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a34000 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:42.970Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d2ebaa100 | BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3600
[task 2022-06-16T00:31:42.970Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d2ebaa100 | BlockShutdown: ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1806
[task 2022-06-16T00:31:42.974Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a37c00 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:42.975Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a37c00 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:42.977Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a34000 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:42.978Z] 00:31:42     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d42a34000 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:43.012Z] 00:31:43     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d2ebaa100 | BlockShutdown: ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1840
[task 2022-06-16T00:31:43.013Z] 00:31:43     INFO -  [Parent 4244, Main Thread] WARNING: ContentParent: id=22d2ebaa100 | BlockShutdown: ShutDownProcess: Close already called.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1847
[task 2022-06-16T00:31:43.078Z] 00:31:43     INFO -  *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: recipe-client-addon-run
[task 2022-06-16T00:31:43.092Z] 00:31:43     INFO -  JavaScript error: resource:///modules/Discovery.jsm, line 106: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2022-06-16T00:31:43.498Z] 00:31:43     INFO -  TEST-INFO | 6516: exit 0
Flags: needinfo?(jstutte)

(In reply to Jens Stutte [:jstutte] from comment #32)

I have difficulties to understand from the failing log what actually determines that we failed.

Hmm, further down the log it seems totally scrambled? I start to doubt we actually see a tabswitch test failure here. It seems we screw up our log buffers somehow?

Flags: needinfo?(csabou)

(In reply to Jens Stutte [:jstutte] from comment #33)

(In reply to Jens Stutte [:jstutte] from comment #32)

I have difficulties to understand from the failing log what actually determines that we failed.

Hmm, further down the log it seems totally scrambled? I start to doubt we actually see a tabswitch test failure here. It seems we screw up our log buffers somehow?

Could a | in the log message cause some trouble?

(FWIW, there is a follow-up patch in bug 1772350 that also removes the |, < and > chars from all log messages, should they ever appear on some command line)

Flags: needinfo?(csabou)
Summary: High frequency TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398' → Intermitent TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398'

(In reply to Cristian Tuns from comment #36)

It looks like you were right, this is no longer high frequency, maybe fixed.
https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&searchStr=linux%2C18.04%2Cx64%2Cwebrender%2Cshippable%2Copt%2Ctalos%2Cperformance%2Ctests%2Cwith%2Csoftware%2Cwebrender%2Cenabled%2Ctest-linux1804-64-shippable-qr%2Fopt-talos-tabswitch-swr-fis-e10s%2Ctabswitch&revision=59d6322396d3dc3d8c93c21fb17daf51de88f77d&selectedTaskRun=foR6ABg3SRyf94BdX-82BA.0

Interesting, that run already contains the follow-up patch from bug 1772350. It might be worth investigating if | (or < / >) are harmful in log messages?

In any case the remaining failure in that logs looks very different from the frequent failures we've seen here before (in particular the log as such looks sane), so things are back to where they were before, it seems.

Summary: Intermitent TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398' → Intermittent TEST-UNEXPECTED-ERROR | tabswitch | could not convert string to float: '8.36334[2196622398'
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
See Also: → 1820372
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.