[12:07:53.987] New invocation is queued and will start shortly
[12:07:55.514] Starting the invocation (attempt 1)
[12:07:55.554] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:07:55.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:07:55.554] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038689623854554080",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3a3f660ea87ebd09d265412065746cadc971d03e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9038689623854554080",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3a3f660ea87ebd09d265412065746cadc971d03e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a3f660ea87ebd09d265412065746cadc971d03e",
"gitiles_ref:refs/heads/master"
]
}
[12:07:58.678] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596197275912822",
"id": "8873259561171935344",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a3f660ea87ebd09d265412065746cadc971d03e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596197278045087",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4238",
"builder:android-bfcache-rel",
"buildset:commit/git/3a3f660ea87ebd09d265412065746cadc971d03e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a3f660ea87ebd09d265412065746cadc971d03e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038689623854554080",
"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/8873259561171935344/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1596197278045241",
"url": "https://ci.chromium.org/b/8873259561171935344",
"utcnow_ts": "1596197278653786"
}
}
[12:07:58.679] Task URL: https://ci.chromium.org/b/8873259561171935344
[12:07:58.679] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:2:0) after 1m0s
[12:08:55.597] Received PubSub notification, asking Buildbucket for the build status
[12:08:55.720] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:58.677] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:2:0)
[12:08:58.677] Timer tick, asking Buildbucket for the build status
[12:08:58.906] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:58.906] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:4:0) after 1m0s
[12:09:58.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:4:0)
[12:09:58.954] Timer tick, asking Buildbucket for the build status
[12:09:59.110] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:59.110] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:5:0) after 1m0s
[12:10:59.201] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:5:0)
[12:10:59.201] Timer tick, asking Buildbucket for the build status
[12:10:59.298] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:59.298] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:6:0) after 1m0s
[12:11:59.412] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:6:0)
[12:11:59.412] Timer tick, asking Buildbucket for the build status
[12:11:59.889] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:59.889] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:7:0) after 1m0s
[12:12:59.937] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:7:0)
[12:12:59.937] Timer tick, asking Buildbucket for the build status
[12:13:00.480] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:00.481] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:8:0) after 1m0s
[12:14:00.545] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:8:0)
[12:14:00.546] Timer tick, asking Buildbucket for the build status
[12:14:00.699] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:00.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:9:0) after 1m0s
[12:15:00.772] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:9:0)
[12:15:00.772] Timer tick, asking Buildbucket for the build status
[12:15:00.899] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:00.899] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:10:0) after 1m0s
[12:16:01.646] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:10:0)
[12:16:01.646] Timer tick, asking Buildbucket for the build status
[12:16:01.987] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:01.987] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:11:0) after 1m0s
[12:17:02.315] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:11:0)
[12:17:02.315] Timer tick, asking Buildbucket for the build status
[12:17:02.429] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:02.429] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:12:0) after 1m0s
[12:18:02.834] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:12:0)
[12:18:02.834] Timer tick, asking Buildbucket for the build status
[12:18:02.982] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:02.982] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:13:0) after 1m0s
[12:19:03.032] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:13:0)
[12:19:03.032] Timer tick, asking Buildbucket for the build status
[12:19:03.103] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:03.103] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:14:0) after 1m0s
[12:20:03.643] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:14:0)
[12:20:03.643] Timer tick, asking Buildbucket for the build status
[12:20:03.734] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:03.734] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:15:0) after 1m0s
[12:21:03.764] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:15:0)
[12:21:03.764] Timer tick, asking Buildbucket for the build status
[12:21:03.840] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:03.840] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:16:0) after 1m0s
[12:22:03.921] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:16:0)
[12:22:03.921] Timer tick, asking Buildbucket for the build status
[12:22:04.033] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:04.033] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:17:0) after 1m0s
[12:23:04.111] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:17:0)
[12:23:04.111] Timer tick, asking Buildbucket for the build status
[12:23:04.491] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:04.491] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:18:0) after 1m0s
[12:24:04.704] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:18:0)
[12:24:04.704] Timer tick, asking Buildbucket for the build status
[12:24:04.786] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:04.786] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:19:0) after 1m0s
[12:25:04.977] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:19:0)
[12:25:04.977] Timer tick, asking Buildbucket for the build status
[12:25:05.117] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:05.117] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:20:0) after 1m0s
[12:26:05.270] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:20:0)
[12:26:05.270] Timer tick, asking Buildbucket for the build status
[12:26:05.393] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:05.393] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:21:0) after 1m0s
[12:27:05.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:21:0)
[12:27:05.519] Timer tick, asking Buildbucket for the build status
[12:27:05.593] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:05.593] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:22:0) after 1m0s
[12:28:05.730] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:22:0)
[12:28:05.730] Timer tick, asking Buildbucket for the build status
[12:28:06.295] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:06.295] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:23:0) after 1m0s
[12:29:06.343] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:23:0)
[12:29:06.343] Timer tick, asking Buildbucket for the build status
[12:29:06.414] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:06.414] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:24:0) after 1m0s
[12:30:06.482] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:24:0)
[12:30:06.482] Timer tick, asking Buildbucket for the build status
[12:30:06.678] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:06.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:25:0) after 1m0s
[12:31:06.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:25:0)
[12:31:06.770] Timer tick, asking Buildbucket for the build status
[12:31:06.981] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:26:0) after 1m0s
[12:32:07.094] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:26:0)
[12:32:07.094] Timer tick, asking Buildbucket for the build status
[12:32:07.353] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:07.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:27:0) after 1m0s
[12:33:07.530] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:27:0)
[12:33:07.530] Timer tick, asking Buildbucket for the build status
[12:33:07.671] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:07.671] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:28:0) after 1m0s
[12:34:07.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:28:0)
[12:34:07.770] Timer tick, asking Buildbucket for the build status
[12:34:07.855] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:07.855] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:29:0) after 1m0s
[12:35:07.879] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:29:0)
[12:35:07.879] Timer tick, asking Buildbucket for the build status
[12:35:07.967] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:07.967] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:30:0) after 1m0s
[12:36:08.114] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:30:0)
[12:36:08.114] Timer tick, asking Buildbucket for the build status
[12:36:08.214] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:08.214] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:31:0) after 1m0s
[12:37:08.281] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:31:0)
[12:37:08.281] Timer tick, asking Buildbucket for the build status
[12:37:08.424] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:08.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:32:0) after 1m0s
[12:38:08.463] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:32:0)
[12:38:08.463] Timer tick, asking Buildbucket for the build status
[12:38:08.588] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:08.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:33:0) after 1m0s
[12:39:08.682] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:33:0)
[12:39:08.682] Timer tick, asking Buildbucket for the build status
[12:39:08.791] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:08.791] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:34:0) after 1m0s
[12:40:08.846] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:34:0)
[12:40:08.846] Timer tick, asking Buildbucket for the build status
[12:40:08.949] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:08.949] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:35:0) after 1m0s
[12:41:08.948] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:35:0)
[12:41:08.948] Timer tick, asking Buildbucket for the build status
[12:41:09.164] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:09.164] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:36:0) after 1m0s
[12:42:09.223] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:36:0)
[12:42:09.223] Timer tick, asking Buildbucket for the build status
[12:42:09.347] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:09.347] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:37:0) after 1m0s
[12:43:09.373] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:37:0)
[12:43:09.373] Timer tick, asking Buildbucket for the build status
[12:43:09.552] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:09.552] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:38:0) after 1m0s
[12:44:09.578] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:38:0)
[12:44:09.578] Timer tick, asking Buildbucket for the build status
[12:44:09.685] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:09.685] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:39:0) after 1m0s
[12:45:09.804] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:39:0)
[12:45:09.804] Timer tick, asking Buildbucket for the build status
[12:45:09.984] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:09.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:40:0) after 1m0s
[12:46:10.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:40:0)
[12:46:10.087] Timer tick, asking Buildbucket for the build status
[12:46:10.403] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:10.403] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:41:0) after 1m0s
[12:47:10.531] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:41:0)
[12:47:10.531] Timer tick, asking Buildbucket for the build status
[12:47:10.666] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:10.666] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:42:0) after 1m0s
[12:48:10.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:42:0)
[12:48:10.685] Timer tick, asking Buildbucket for the build status
[12:48:10.881] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:10.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:43:0) after 1m0s
[12:49:10.937] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:43:0)
[12:49:10.937] Timer tick, asking Buildbucket for the build status
[12:49:11.117] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:11.117] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:44:0) after 1m0s
[12:50:11.192] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:44:0)
[12:50:11.192] Timer tick, asking Buildbucket for the build status
[12:50:11.404] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:11.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:45:0) after 1m0s
[12:51:11.444] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:45:0)
[12:51:11.444] Timer tick, asking Buildbucket for the build status
[12:51:11.552] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:11.552] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:46:0) after 1m0s
[12:52:11.655] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:46:0)
[12:52:11.655] Timer tick, asking Buildbucket for the build status
[12:52:11.987] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:11.987] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:47:0) after 1m0s
[12:53:12.092] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:47:0)
[12:53:12.092] Timer tick, asking Buildbucket for the build status
[12:53:12.221] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:12.221] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:48:0) after 1m0s
[12:54:12.297] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:48:0)
[12:54:12.297] Timer tick, asking Buildbucket for the build status
[12:54:12.393] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:12.393] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:49:0) after 1m0s
[12:55:12.521] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:49:0)
[12:55:12.521] Timer tick, asking Buildbucket for the build status
[12:55:12.750] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:12.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:50:0) after 1m0s
[12:56:12.774] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:50:0)
[12:56:12.774] Timer tick, asking Buildbucket for the build status
[12:56:12.846] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:12.846] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:51:0) after 1m0s
[12:57:13.057] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:51:0)
[12:57:13.057] Timer tick, asking Buildbucket for the build status
[12:57:13.255] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:13.255] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:52:0) after 1m0s
[12:58:13.452] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:52:0)
[12:58:13.452] Timer tick, asking Buildbucket for the build status
[12:58:13.633] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:13.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:53:0) after 1m0s
[12:59:13.737] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:53:0)
[12:59:13.737] Timer tick, asking Buildbucket for the build status
[12:59:13.988] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:13.988] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:54:0) after 1m0s
[13:00:14.125] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:54:0)
[13:00:14.125] Timer tick, asking Buildbucket for the build status
[13:00:14.362] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:14.362] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:55:0) after 1m0s
[13:01:14.369] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:55:0)
[13:01:14.369] Timer tick, asking Buildbucket for the build status
[13:01:15.945] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:15.945] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:56:0) after 1m0s
[13:02:15.982] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:56:0)
[13:02:15.982] Timer tick, asking Buildbucket for the build status
[13:02:16.125] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:16.125] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:57:0) after 1m0s
[13:03:16.276] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:57:0)
[13:03:16.276] Timer tick, asking Buildbucket for the build status
[13:03:16.703] Build 8873259561171935344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:16.703] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9038689623854554080:58:0) after 1m0s
[13:03:18.541] Received PubSub notification, asking Buildbucket for the build status
[13:03:18.974] Build 8873259561171935344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:03:18.974] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596200597819088",
"created_by": "project:chromium",
"created_ts": "1596197275912822",
"failure_reason": "BUILD_FAILURE",
"id": "8873259561171935344",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a3f660ea87ebd09d265412065746cadc971d03e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipCrossSiteEntry/1\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/2\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/0\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/1\\n\\n- ...8 more failure(s) (12 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-2-4qyh\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4238, \"got_angle_revision\": \"cbd5bee81dc6cfe8bf6e4aed0d2bfcf5c085ad0f\", \"got_dawn_revision\": \"fada50119020483021a6b620c8fb85d62fc3e8f0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"3a3f660ea87ebd09d265412065746cadc971d03e\", \"got_revision_cp\": \"refs/heads/master@{#793565}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"aa5bd7657e6cc140b95f3f070f011c65213a2cc1\", \"got_v8_revision\": \"ba3620877744f91b516c7ca1895f4341100df874\", \"got_v8_revision_cp\": \"refs/heads/8.6.296@{#1}\", \"got_webrtc_revision\": \"ee8c246be71e4dd8d4ca34011d3b3e40faf576a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31816}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a3f660ea87ebd09d265412065746cadc971d03e\", \"swarm_hashes\": {\"android_browsertests\": \"7317ca8c0a63ad873ef55b096aa607d2929ebbc9\", \"chrome_public_test_apk\": \"7aeee876f20159b9b8140a813d949d7e99abce23\", \"content_browsertests\": \"c921bb20258c42c84f0f9c2e77c5e94ce3b5f8b8\", \"content_shell_test_apk\": \"9d78fe50ef36d79828973d3525def954a8bf8976\", \"content_unittests\": \"a577f35b4f4786c55962567b27d7f66dddfe48f6\", \"unit_tests\": \"d3a19553a1f0212c0e3ccf5f3da259a796e9c769\"}, \"swarm_hashes_refs/heads/master(at){#793565}_without_patch\": {\"android_browsertests\": \"7317ca8c0a63ad873ef55b096aa607d2929ebbc9\", \"chrome_public_test_apk\": \"7aeee876f20159b9b8140a813d949d7e99abce23\", \"content_browsertests\": \"c921bb20258c42c84f0f9c2e77c5e94ce3b5f8b8\", \"content_shell_test_apk\": \"9d78fe50ef36d79828973d3525def954a8bf8976\", \"content_unittests\": \"a577f35b4f4786c55962567b27d7f66dddfe48f6\", \"unit_tests\": \"d3a19553a1f0212c0e3ccf5f3da259a796e9c769\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0cd6cf41ec42413cc9e5bdc0dde565dc7bee061bff1a52db1e25d07760950df7_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_479a9ab4e006ab9f281a30e38952e2c387e86fd61a54f6cd68389780c770d389_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_76dcd9458dba0f557afbb3459a52975a2daf99a7b9f046acf90f6477a840a9f3_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_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-2-4qyh\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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.7.12\"], \"server_version\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipCrossSiteEntry/1\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/2\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/0\\n\\n- All/ProactivelySwapBrowsingInstancesSameSiteTest.HistoryNavigationReusesProcess_SkipSameSiteEntry/1\\n\\n- ...8 more failure(s) (12 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1596197334781506",
"status": "COMPLETED",
"status_changed_ts": "1596197335166279",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4238",
"builder:android-bfcache-rel",
"buildset:commit/git/3a3f660ea87ebd09d265412065746cadc971d03e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a3f660ea87ebd09d265412065746cadc971d03e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038689623854554080",
"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/8873259561171935344/+/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:4dbd971afaf9b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1596200598044341",
"url": "https://ci.chromium.org/b/8873259561171935344",
"utcnow_ts": "1596200598939544"
}
}
[13:03:18.974] Invocation finished in 55m25.003661094s with status FAILED