[00:34:22.123] New invocation is queued and will start shortly
[00:34:23.191] Starting the invocation (attempt 1)
[00:34:23.229] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087"
[00:34:23.229] Popped gitiles commit info from properties and tags
[00:34:23.229] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:34:23.230] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:34:23.230] Buildbucket request:
{
"requestId": "9017895954782060688",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-bfcache-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017895954782060688"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-bfcache-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:34:24.410] Scheduled build:
{
"id": "8852465892828670352",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-bfcache-rel"
},
"number": 8313,
"createdBy": "project:chromium",
"createTime": "2021-03-18T00:34:23.354061Z",
"updateTime": "2021-03-18T00:34:24.018992Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087",
"ref": "refs/heads/master"
}
}
}
[00:34:24.410] Task URL: https://cr-buildbucket.appspot.com/build/8852465892828670352
[00:34:24.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:2:0) after 4m43s
[00:37:05.655] Received PubSub notification, asking Buildbucket for the build status
[00:37:05.808] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:07.525] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:2:0)
[00:39:07.553] Build status: STARTED
[00:39:07.553] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:4:0) after 5m42s
[00:44:49.807] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:4:0)
[00:44:49.838] Build status: STARTED
[00:44:49.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:5:0) after 3m44s
[00:48:33.876] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:5:0)
[00:48:34.048] Build status: STARTED
[00:48:34.048] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:6:0) after 1m39s
[00:50:13.137] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:6:0)
[00:50:13.137] Timer tick, asking Buildbucket for the build status
[00:50:13.289] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:13.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:7:0) after 1m0s
[00:51:13.439] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:7:0)
[00:51:13.475] Build status: STARTED
[00:51:13.475] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:8:0) after 9m40s
[01:00:53.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:8:0)
[01:00:53.526] Build status: STARTED
[01:00:53.526] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:9:0) after 6m19s
[01:07:12.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:9:0)
[01:07:12.567] Timer tick, asking Buildbucket for the build status
[01:07:12.827] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:12.827] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:10:0) after 1m0s
[01:08:12.864] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:10:0)
[01:08:12.893] Build status: STARTED
[01:08:12.893] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:11:0) after 7m54s
[01:16:07.000] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:11:0)
[01:16:07.041] Build status: STARTED
[01:16:07.041] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:12:0) after 9m24s
[01:25:31.127] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:12:0)
[01:25:31.158] Build status: STARTED
[01:25:31.158] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:13:0) after 1m43s
[01:27:14.209] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:13:0)
[01:27:14.252] Build status: STARTED
[01:27:14.252] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:14:0) after 8m13s
[01:35:27.314] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:14:0)
[01:35:27.314] Timer tick, asking Buildbucket for the build status
[01:35:27.422] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:27.422] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:15:0) after 1m0s
[01:36:27.442] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:15:0)
[01:36:27.482] Build status: STARTED
[01:36:27.482] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:16:0) after 8m54s
[01:45:21.498] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:16:0)
[01:45:21.526] Build status: STARTED
[01:45:21.526] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:17:0) after 9m55s
[01:55:16.555] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:17:0)
[01:55:16.587] Build status: STARTED
[01:55:16.587] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:18:0) after 7m13s
[02:02:29.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:18:0)
[02:02:29.642] Build status: STARTED
[02:02:29.642] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:19:0) after 9m53s
[02:12:22.663] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:19:0)
[02:12:22.663] Timer tick, asking Buildbucket for the build status
[02:12:22.766] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:22.766] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:20:0) after 1m0s
[02:13:22.914] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:20:0)
[02:13:22.914] Timer tick, asking Buildbucket for the build status
[02:13:23.014] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:23.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:21:0) after 1m0s
[02:14:23.130] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:21:0)
[02:14:23.130] Timer tick, asking Buildbucket for the build status
[02:14:23.199] Build 8852465892828670352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:23.199] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9017895954782060688:22:0) after 1m0s
[02:14:48.207] Received PubSub notification, asking Buildbucket for the build status
[02:14:48.565] Build 8852465892828670352: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:14:48.565] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616033687922674",
"created_by": "project:chromium",
"created_ts": "1616027663354061",
"failure_reason": "BUILD_FAILURE",
"id": "8852465892828670352",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.compositor.layouts.LayoutManagerTest#testLayoutObserverNotification_ShowAndHide_TabSwitcher\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#startSurfaceRecordHistogramsTest__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#startSurfaceRecordHistogramsTest__NoInstant_Return\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/PrerenderBrowserTest.PrerenderedPageSameSiteNavigation/MPArch\\n\\n- All/PrerenderBrowserTest.DeferCrossOriginRedirectsOnSubframeNavigation/WebContents\\n\\n- All/PrerenderBrowserTest.RenderFrameHostLifecycleState/MPArch\\n\\n- ...2 more failure(s) (5 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-17-4ij4\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 8313, \"got_angle_revision\": \"f6eccc203e0f45736103eee629961b865d132d5a\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087\", \"got_revision_cp\": \"refs/heads/master@{#864032}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"fc442e89638f38a476e53abca22aafde28c55aa5\", \"got_v8_revision_cp\": \"refs/heads/9.1.147@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#864032}_without_patch\": {\"android_browsertests\": \"b5c4b65e2cddadc724f28821e98643e442d804d9\", \"chrome_public_test_apk\": \"5378d9cc91a9e90320def9cf0d476dcbc5082b7e\", \"content_browsertests\": \"c96ad6eb858765aa4cc9ceddf17190c08623b8ad\", \"content_shell_test_apk\": \"63d8cf0fec8fbd6bf30c00460593442ea281ecdd\", \"content_unittests\": \"86b193bc09948d064c2221a73b758636d6e3386a\", \"unit_tests\": \"de514178ab5664d877bf05777a991545af1639e9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1867ea95e01d3ef15634c6eb0637af698753fb1de91acba07f1b302e169858e3_v2\", \"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-17-4ij4\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.compositor.layouts.LayoutManagerTest#testLayoutObserverNotification_ShowAndHide_TabSwitcher\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#startSurfaceRecordHistogramsTest__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#startSurfaceRecordHistogramsTest__NoInstant_Return\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/PrerenderBrowserTest.PrerenderedPageSameSiteNavigation/MPArch\\n\\n- All/PrerenderBrowserTest.DeferCrossOriginRedirectsOnSubframeNavigation/WebContents\\n\\n- All/PrerenderBrowserTest.RenderFrameHostLifecycleState/MPArch\\n\\n- ...2 more failure(s) (5 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616027825184661",
"status": "COMPLETED",
"status_changed_ts": "1616027825447059",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/8313",
"builder:android-bfcache-rel",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c68cdc0cd4072e8a6b50a12e7e40ce0b6fdd087",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017895954782060688",
"scheduler_job_id:chromium/android-bfcache-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852465892828670352/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:525b92cd55661110",
"user_agent:luci-scheduler"
],
"updated_ts": "1616033687924049",
"url": "https://ci.chromium.org/b/8852465892828670352",
"utcnow_ts": "1616033688555278"
}
}
[02:14:48.565] Invocation finished in 1h40m26.456899712s with status FAILED