https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/linux-chromeos-dbg/8868

https://logs.chromium.org/logs/chromium/buildbucket/cr-buildbucket.appspot.com/8930022631351952304/+/steps/single_process_mash_browser_tests/0/logs/ChromeBrowserMainBrowserTest.VariationsServiceStartsRequestOnNetworkChange/0
[ RUN      ] ChromeBrowserMainBrowserTest.VariationsServiceStartsRequestOnNetworkChange
[2080:2080:1112/154433.197468:WARNING:chrome_browser_main_chromeos.cc(543)] Running as stub user with profile dir: test-user
[2080:2080:1112/154433.760272:WARNING:easy_unlock_service_regular.cc(522)] EasyUnlockServiceRegular::IsInLegacyHostMode: DeviceSyncClient not ready. Returning false.
[2080:2080:1112/154433.888483:INFO:remote_commands_service.cc(38)] Fetching remote commands.
[2080:2080:1112/154433.888609:WARNING:remote_commands_service.cc(40)] Client is not registered.
[2080:2080:1112/154433.888843:INFO:remote_commands_invalidator.cc(32)] Initialize RemoteCommandsInvalidator.
[2080:2080:1112/154433.889059:INFO:remote_commands_invalidator.cc(57)] Starting RemoteCommandsInvalidator.
[2080:2080:1112/154433.889233:INFO:remote_commands_invalidator.cc(123)] RemoteCommandsInvalidator ReloadPolicyData.
[2080:2080:1112/154433.889354:INFO:remote_commands_invalidator.cc(167)] Unregister RemoteCommandsInvalidator.
[2080:2080:1112/154434.094994:WARNING:wallpaper_controller_client.cc(358)] Cannot get wallpaper files id in RemovePolicyWallpaper. This should never happen under normal circumstances.
tracing_service_impl.cc: Producer 1 connected
[2080:2080:1112/154434.639575:WARNING:diagnosticsd_bridge.cc(90)] The diagnosticsd D-Bus service is unavailable
tracing_service_impl.cc: Producer 1 registered data source "org.chromium.trace_event"
tracing_service_impl.cc: Producer 1 registered data source "org.chromium.trace_metadata"
tracing_service_impl.cc: Producer 2 connected
tracing_service_impl.cc: Producer 2 registered data source "org.chromium.trace_event"
tracing_service_impl.cc: Producer 3 connected
tracing_service_impl.cc: Producer 3 registered data source "org.chromium.trace_event"
[2080:2080:1112/154434.912226:ERROR:gpu_interface_provider.cc(87)] Not implemented reached in virtual void content::GpuInterfaceProvider::RegisterOzoneGpuInterfaces(service_manager::BinderRegistry *)
tracing_service_impl.cc: Producer 4 connected
tracing_service_impl.cc: Producer 4 registered data source "org.chromium.trace_event"
[2080:2080:1112/154435.270508:ERROR:remote_text_input_client.cc(158)] Not implemented reached in virtual void RemoteTextInputClient::OnInputMethodChanged()
[2080:2080:1112/154435.270558:ERROR:remote_text_input_client.cc(115)] Not implemented reached in virtual ui::TextInputClient::FocusReason RemoteTextInputClient::GetFocusReason() const
[2080:2080:1112/154435.270582:ERROR:remote_text_input_client.cc(200)] Not implemented reached in virtual bool RemoteTextInputClient::ShouldDoLearning()
[2080:2080:1112/154435.270644:ERROR:remote_text_input_client.cc(109)] Not implemented reached in virtual bool RemoteTextInputClient::HasCompositionText() const
[2080:2080:1112/154435.270668:ERROR:remote_text_input_client.cc(121)] Not implemented reached in virtual bool RemoteTextInputClient::GetTextRange(gfx::Range *) const
[2080:2080:1112/154435.270712:ERROR:remote_text_input_client.cc(176)] Not implemented reached in virtual void RemoteTextInputClient::EnsureCaretNotInRect(const gfx::Rect &)
[2080:2080:1112/154435.305848:ERROR:layer_tree_host_impl.cc(3144)] Forcing zero-copy tile initialization as worker context is missing
[2080:2080:1112/154435.322171:ERROR:layer_tree_host_impl.cc(3144)] Forcing zero-copy tile initialization as worker context is missing
[2080:2080:1112/154435.404857:ERROR:layer_tree_host_impl.cc(3144)] Forcing zero-copy tile initialization as worker context is missing
../../chrome/browser/chrome_browser_main_browsertest.cc:155: Failure
Expected equality of these values:
  initial_request_count + 1
    Which is: 2
  final_request_count
    Which is: 1
Stack trace:
#0 0x55aaed18d2c0 StackTraceGetter::CurrentStackTrace()
#1 0x55aaed1aeb37 testing::internal::UnitTestImpl::CurrentOsStackTraceExceptTop()
#2 0x55aaed1adfa3 testing::internal::AssertHelper::operator=()
#3 0x55aae9623a34 (anonymous namespace)::ChromeBrowserMainBrowserTest_VariationsServiceStartsRequestOnNetworkChange_Test::RunTestOnMainThread()
#4 0x55aaf1bf8a45 content::BrowserTestBase::ProxyRunTestOnMainThreadLoop()
#5 0x55aae9497cbd _ZN4base8internal13FunctorTraitsIM25RenderViewContextMenuBaseFvvEvE6InvokeIS4_P21RenderViewContextMenuJEEEvT_OT0_DpOT1_
#6 0x55aae9497c04 _ZN4base8internal12InvokeHelperILb0EvE8MakeItSoIM25RenderViewContextMenuBaseFvvEJP21RenderViewContextMenuEEEvOT_DpOT0_
#7 0x55aaf1bfb8a5 _ZN4base8internal7InvokerINS0_9BindStateIMN7content15BrowserTestBaseEFvvEJNS0_17UnretainedWrapperIS4_EEEEEFvvEE7RunImplIRKS6_RKNSt3__15tupleIJS8_EEEJLm0EEEEvOT_OT0_NSF_16integer_sequenceImJXspT1_EEEE
#8 0x55aaf1bfb7dc _ZN4base8internal7InvokerINS0_9BindStateIMN7content15BrowserTestBaseEFvvEJNS0_17UnretainedWrapperIS4_EEEEEFvvEE3RunEPNS0_13BindStateBaseE
#9 0x55aae948830d _ZNKR4base17RepeatingCallbackIFvvEE3RunEv
#10 0x55aaf094604d ChromeBrowserMainParts::PreMainMessageLoopRunImpl()
#11 0x55aaf09440b8 ChromeBrowserMainParts::PreMainMessageLoopRun()
#12 0x55aaec330196 chromeos::ChromeBrowserMainPartsChromeos::PreMainMessageLoopRun()
#13 0x7f7e0f8db2fc content::BrowserMainLoop::PreMainMessageLoopRun()
#14 0x7f7e0e45503d _ZN4base8internal13FunctorTraitsIMN7content12ChildProcessEFvvEvE6InvokeIS5_PS3_JEEEvT_OT0_DpOT1_
#15 0x7f7e0e454f84 _ZN4base8internal12InvokeHelperILb0EvE8MakeItSoIMN7content12ChildProcessEFvvEJPS5_EEEvOT_DpOT0_
#16 0x7f7e0f8e1205 _ZN4base8internal7InvokerINS0_9BindStateIMN7content15BrowserMainLoopEFivEJNS0_17UnretainedWrapperIS4_EEEEEFivEE7RunImplIRKS6_RKNSt3__15tupleIJS8_EEEJLm0EEEEiOT_OT0_NSF_16integer_sequenceImJXspT1_EEEE
#17 0x7f7e0f8e113c _ZN4base8internal7InvokerINS0_9BindStateIMN7content15BrowserMainLoopEFivEJNS0_17UnretainedWrapperIS4_EEEEEFivEE3RunEPNS0_13BindStateBaseE
#18 0x7f7e0e35692d _ZNKR4base17RepeatingCallbackIFvvEE3RunEv
#19 0x7f7e108df2ad content::StartupTaskRunner::RunAllTasksNow()
#20 0x7f7e0f8d6daf content::BrowserMainLoop::CreateStartupTasks()
#21 0x7f7e0f8e38c2 content::BrowserMainRunnerImpl::Initialize()
#22 0x7f7e0f8ce1f9 content::BrowserMain()
#23 0x7f7e11c44840 content::RunBrowserProcessMain()
#24 0x7f7e11c47409 content::ContentMainRunnerImpl::Run()
#25 0x7f7e11c3d65c content::ContentServiceManagerMainDelegate::RunEmbedderProcess()
#26 0x7f7de6cfd681 service_manager::Main()
#27 0x7f7e11c41f75 content::ContentMain()
#28 0x55aaf1bf7ff2 content::BrowserTestBase::SetUp()
#29 0x55aaf07dd70b InProcessBrowserTest::SetUp()
#30 0x55aae94e61ae _ZN7testing8internal12InvokeHelperIRKNSt3__16vectorIPN12user_manager4UserENS2_9allocatorIS6_EEEENS2_5tupleIJEEEE12InvokeMethodIN8chromeos15MockUserManagerEMSH_KFSB_vEEESB_PT_T0_RKSD_

tracing_service_impl.cc: Producer 2 disconnected
tracing_service_impl.cc: Producer 3 disconnected
[2080:2080:1112/154436.562319:INFO:remote_commands_invalidator.cc(45)] Shutdown RemoteCommandsInvalidator.
[2080:2080:1112/154436.562546:INFO:remote_commands_invalidator.cc(68)] Stopping RemoteCommandsInvalidator.
[2080:2080:1112/154436.562640:INFO:remote_commands_invalidator.cc(167)] Unregister RemoteCommandsInvalidator.
[2080:2165:1112/154436.713043:WARNING:discardable_shared_memory_manager.cc(410)] Some MojoDiscardableSharedMemoryManagerImpls are still alive. They will be leaked.
[2080:2349:1112/154436.724593:WARNING:internal_linux.cc(64)] Failed to read /proc/2190/stat
[2080:2080:1112/154436.726010:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.
[2080:2080:1112/154436.726066:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.
[  FAILED  ] ChromeBrowserMainBrowserTest.VariationsServiceStartsRequestOnNetworkChange, where TypeParam =  and GetParam() =  (5366 ms)

This patch seems suspicious.
https://chromium-review.googlesource.com/c/chromium/src/+/1329897

rmcelrath@, would you take a look?  Your patch seems related.

Yeah, that's probably the cause. I'll revert.

I forgot to tag the revert with this bug. It was reverted in crrev.com/c/1333248.

+Jun

See also  bug 902784 , where this test is failing (flaking?) on official bot but not reproducible locally.

Could there be a timing condition in that test?

Yes, it could be the timing. With the help from chrome infra team, I can now ssh to one of the official build bot where the test consistently fails, and can reproduce the test failure. I will debug what causes this.