[22:19:50.146] New invocation is queued and will start shortly
[22:19:51.360] Starting the invocation (attempt 1)
[22:19:51.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:19:51.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:19:51.406] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9042546793727944960",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1ee34734bf0231586c133ccdfe2dbeef93608163\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9042546793727944960",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/1ee34734bf0231586c133ccdfe2dbeef93608163",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ee34734bf0231586c133ccdfe2dbeef93608163",
"gitiles_ref:refs/heads/master"
]
}
[22:19:52.763] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1592518791580752",
"id": "8877116731558748320",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ee34734bf0231586c133ccdfe2dbeef93608163\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1592518792527356",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3423",
"builder:android-bfcache-rel",
"buildset:commit/git/1ee34734bf0231586c133ccdfe2dbeef93608163",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ee34734bf0231586c133ccdfe2dbeef93608163",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042546793727944960",
"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/8877116731558748320/+/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": "1592518792527495",
"url": "https://ci.chromium.org/b/8877116731558748320",
"utcnow_ts": "1592518792744191"
}
}
[22:19:52.763] Task URL: https://ci.chromium.org/b/8877116731558748320
[22:19:52.763] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:2:0) after 1m0s
[22:20:27.692] Received PubSub notification, asking Buildbucket for the build status
[22:20:27.811] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:52.809] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:2:0)
[22:20:52.809] Timer tick, asking Buildbucket for the build status
[22:20:52.984] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:52.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:4:0) after 1m0s
[22:21:53.033] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:4:0)
[22:21:53.033] Timer tick, asking Buildbucket for the build status
[22:21:53.259] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:53.259] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:5:0) after 1m0s
[22:22:53.343] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:5:0)
[22:22:53.343] Timer tick, asking Buildbucket for the build status
[22:22:53.607] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:53.608] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:6:0) after 1m0s
[22:23:53.662] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:6:0)
[22:23:53.662] Timer tick, asking Buildbucket for the build status
[22:23:54.015] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:54.015] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:7:0) after 1m0s
[22:24:54.023] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:7:0)
[22:24:54.023] Timer tick, asking Buildbucket for the build status
[22:24:54.261] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:54.261] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:8:0) after 1m0s
[22:25:54.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:8:0)
[22:25:54.312] Timer tick, asking Buildbucket for the build status
[22:25:54.497] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:54.497] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:9:0) after 1m0s
[22:26:54.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:9:0)
[22:26:54.597] Timer tick, asking Buildbucket for the build status
[22:26:55.164] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:55.164] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:10:0) after 1m0s
[22:27:55.177] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:10:0)
[22:27:55.177] Timer tick, asking Buildbucket for the build status
[22:27:55.413] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:55.413] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:11:0) after 1m0s
[22:28:55.499] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:11:0)
[22:28:55.499] Timer tick, asking Buildbucket for the build status
[22:28:55.848] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:55.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:12:0) after 1m0s
[22:29:55.904] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:12:0)
[22:29:55.904] Timer tick, asking Buildbucket for the build status
[22:29:56.133] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:56.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:13:0) after 1m0s
[22:30:56.151] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:13:0)
[22:30:56.151] Timer tick, asking Buildbucket for the build status
[22:30:56.306] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:56.306] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:14:0) after 1m0s
[22:31:56.347] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:14:0)
[22:31:56.347] Timer tick, asking Buildbucket for the build status
[22:31:57.063] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:57.063] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:15:0) after 1m0s
[22:32:57.114] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:15:0)
[22:32:57.114] Timer tick, asking Buildbucket for the build status
[22:32:57.288] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:57.288] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:16:0) after 1m0s
[22:33:57.308] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:16:0)
[22:33:57.308] Timer tick, asking Buildbucket for the build status
[22:33:57.480] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:57.480] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:17:0) after 1m0s
[22:34:57.499] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:17:0)
[22:34:57.499] Timer tick, asking Buildbucket for the build status
[22:34:57.761] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:57.761] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:18:0) after 1m0s
[22:35:57.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:18:0)
[22:35:57.784] Timer tick, asking Buildbucket for the build status
[22:35:58.005] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:58.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:19:0) after 1m0s
[22:36:58.071] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:19:0)
[22:36:58.071] Timer tick, asking Buildbucket for the build status
[22:36:58.372] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:58.372] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:20:0) after 1m0s
[22:37:58.389] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:20:0)
[22:37:58.389] Timer tick, asking Buildbucket for the build status
[22:37:58.547] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:58.547] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:21:0) after 1m0s
[22:38:58.622] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:21:0)
[22:38:58.622] Timer tick, asking Buildbucket for the build status
[22:38:58.962] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:58.962] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:22:0) after 1m0s
[22:39:59.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:22:0)
[22:39:59.079] Timer tick, asking Buildbucket for the build status
[22:39:59.376] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:59.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:23:0) after 1m0s
[22:40:59.462] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:23:0)
[22:40:59.462] Timer tick, asking Buildbucket for the build status
[22:40:59.627] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:59.627] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:24:0) after 1m0s
[22:41:59.688] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:24:0)
[22:41:59.688] Timer tick, asking Buildbucket for the build status
[22:41:59.950] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:59.950] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:25:0) after 1m0s
[22:42:59.953] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:25:0)
[22:42:59.953] Timer tick, asking Buildbucket for the build status
[22:43:00.156] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:00.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:26:0) after 1m0s
[22:44:00.196] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:26:0)
[22:44:00.196] Timer tick, asking Buildbucket for the build status
[22:44:00.371] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:00.371] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:27:0) after 1m0s
[22:45:00.423] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:27:0)
[22:45:00.423] Timer tick, asking Buildbucket for the build status
[22:45:00.594] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:00.594] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:28:0) after 1m0s
[22:46:01.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:28:0)
[22:46:01.083] Timer tick, asking Buildbucket for the build status
[22:46:01.581] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:01.648] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:29:0) after 1m0s
[22:47:01.689] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:29:0)
[22:47:01.689] Timer tick, asking Buildbucket for the build status
[22:47:02.192] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:30:0) after 1m0s
[22:48:02.198] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:30:0)
[22:48:02.198] Timer tick, asking Buildbucket for the build status
[22:48:02.749] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:02.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:31:0) after 1m0s
[22:49:02.773] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:31:0)
[22:49:02.773] Timer tick, asking Buildbucket for the build status
[22:49:03.079] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:03.079] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:32:0) after 1m0s
[22:50:03.378] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:32:0)
[22:50:03.378] Timer tick, asking Buildbucket for the build status
[22:50:04.458] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:33:0) after 1m0s
[22:51:04.545] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:33:0)
[22:51:04.545] Timer tick, asking Buildbucket for the build status
[22:51:04.709] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:04.709] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:34:0) after 1m0s
[22:52:04.998] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:34:0)
[22:52:04.998] Timer tick, asking Buildbucket for the build status
[22:52:05.203] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:05.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:35:0) after 1m0s
[22:53:05.680] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:35:0)
[22:53:05.680] Timer tick, asking Buildbucket for the build status
[22:53:06.368] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:06.368] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:36:0) after 1m0s
[22:54:06.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:36:0)
[22:54:06.749] Timer tick, asking Buildbucket for the build status
[22:54:07.037] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:07.037] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:37:0) after 1m0s
[22:55:07.486] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:37:0)
[22:55:07.486] Timer tick, asking Buildbucket for the build status
[22:55:07.579] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:07.579] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:38:0) after 1m0s
[22:56:07.894] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:38:0)
[22:56:07.894] Timer tick, asking Buildbucket for the build status
[22:56:08.314] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:08.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:39:0) after 1m0s
[22:57:08.467] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:39:0)
[22:57:08.467] Timer tick, asking Buildbucket for the build status
[22:57:08.568] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:08.568] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:40:0) after 1m0s
[22:58:08.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:40:0)
[22:58:08.685] Timer tick, asking Buildbucket for the build status
[22:58:09.112] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:09.112] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:41:0) after 1m0s
[22:59:09.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:41:0)
[22:59:09.191] Timer tick, asking Buildbucket for the build status
[22:59:09.316] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.316] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:42:0) after 1m0s
[23:00:09.407] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:42:0)
[23:00:09.407] Timer tick, asking Buildbucket for the build status
[23:00:10.371] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:10.371] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:43:0) after 1m0s
[23:01:10.611] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:43:0)
[23:01:10.611] Timer tick, asking Buildbucket for the build status
[23:01:11.163] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:11.163] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:44:0) after 1m0s
[23:02:11.230] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:44:0)
[23:02:11.230] Timer tick, asking Buildbucket for the build status
[23:02:11.386] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:11.386] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:45:0) after 1m0s
[23:03:11.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:45:0)
[23:03:11.465] Timer tick, asking Buildbucket for the build status
[23:03:11.661] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:11.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:46:0) after 1m0s
[23:04:11.792] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:46:0)
[23:04:11.792] Timer tick, asking Buildbucket for the build status
[23:04:11.935] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:11.935] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:47:0) after 1m0s
[23:05:12.104] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:47:0)
[23:05:12.104] Timer tick, asking Buildbucket for the build status
[23:05:12.334] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:12.334] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:48:0) after 1m0s
[23:06:12.416] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:48:0)
[23:06:12.416] Timer tick, asking Buildbucket for the build status
[23:06:12.602] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:12.602] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:49:0) after 1m0s
[23:07:12.939] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:49:0)
[23:07:12.939] Timer tick, asking Buildbucket for the build status
[23:07:13.405] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:13.405] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:50:0) after 1m0s
[23:08:13.569] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:50:0)
[23:08:13.569] Timer tick, asking Buildbucket for the build status
[23:08:14.011] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:14.012] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:51:0) after 1m0s
[23:09:14.060] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:51:0)
[23:09:14.060] Timer tick, asking Buildbucket for the build status
[23:09:14.524] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.524] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:52:0) after 1m0s
[23:10:14.614] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:52:0)
[23:10:14.614] Timer tick, asking Buildbucket for the build status
[23:10:14.776] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:14.776] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:53:0) after 1m0s
[23:11:14.843] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:53:0)
[23:11:14.843] Timer tick, asking Buildbucket for the build status
[23:11:15.001] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:54:0) after 1m0s
[23:12:15.091] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:54:0)
[23:12:15.091] Timer tick, asking Buildbucket for the build status
[23:12:15.300] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:15.300] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:55:0) after 1m0s
[23:13:15.337] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:55:0)
[23:13:15.337] Timer tick, asking Buildbucket for the build status
[23:13:15.466] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:15.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:56:0) after 1m0s
[23:14:15.505] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:56:0)
[23:14:15.505] Timer tick, asking Buildbucket for the build status
[23:14:15.874] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:15.874] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:57:0) after 1m0s
[23:15:15.993] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:57:0)
[23:15:15.993] Timer tick, asking Buildbucket for the build status
[23:15:16.417] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:16.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:58:0) after 1m0s
[23:16:16.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:58:0)
[23:16:16.530] Timer tick, asking Buildbucket for the build status
[23:16:16.786] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:16.786] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:59:0) after 1m0s
[23:17:16.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:59:0)
[23:17:16.859] Timer tick, asking Buildbucket for the build status
[23:17:18.033] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:18.033] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:60:0) after 1m0s
[23:18:18.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:60:0)
[23:18:18.059] Timer tick, asking Buildbucket for the build status
[23:18:18.495] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:18.495] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:61:0) after 1m0s
[23:19:18.551] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:61:0)
[23:19:18.551] Timer tick, asking Buildbucket for the build status
[23:19:18.768] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:18.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:62:0) after 1m0s
[23:20:18.813] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:62:0)
[23:20:18.813] Timer tick, asking Buildbucket for the build status
[23:20:18.938] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:18.938] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:63:0) after 1m0s
[23:21:18.959] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:63:0)
[23:21:18.959] Timer tick, asking Buildbucket for the build status
[23:21:19.068] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:19.068] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:64:0) after 1m0s
[23:22:19.136] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:64:0)
[23:22:19.136] Timer tick, asking Buildbucket for the build status
[23:22:19.287] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:19.287] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:65:0) after 1m0s
[23:23:19.346] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:65:0)
[23:23:19.346] Timer tick, asking Buildbucket for the build status
[23:23:19.514] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:66:0) after 1m0s
[23:24:19.535] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:66:0)
[23:24:19.535] Timer tick, asking Buildbucket for the build status
[23:24:19.688] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:19.688] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:67:0) after 1m0s
[23:25:19.731] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:67:0)
[23:25:19.731] Timer tick, asking Buildbucket for the build status
[23:25:20.073] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:20.073] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:68:0) after 1m0s
[23:26:20.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:68:0)
[23:26:20.154] Timer tick, asking Buildbucket for the build status
[23:26:20.385] Build 8877116731558748320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:20.385] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042546793727944960:69:0) after 1m0s
[23:27:08.903] Received PubSub notification, asking Buildbucket for the build status
[23:27:09.118] Build 8877116731558748320: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:27:09.118] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1592522827798589",
"created_by": "project:chromium",
"created_ts": "1592518791580752",
"failure_reason": "BUILD_FAILURE",
"id": "8877116731558748320",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ee34734bf0231586c133ccdfe2dbeef93608163\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- ...122 more failure(s) (126 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- All/RenderFrameHostManagerDefaultProcessTest.NavigationRacesWithSitelessCommitInDefaultProcess/1\\n\\n- ...25 more failure(s) (27 total)...\\n\\n#### ...1 more test(s)...\"}, \"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-91-ryts\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 3423, \"got_angle_revision\": \"7b282eb5f8fbfdb1409e98cf5366ba45ce53e107\", \"got_dawn_revision\": \"581c407cd20badc71e3b8e5536e5a1ac47d5b6ba\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"1ee34734bf0231586c133ccdfe2dbeef93608163\", \"got_revision_cp\": \"refs/heads/master@{#780047}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"974cfa9fbbdb72c25baef8d66e8e914e08631773\", \"got_v8_revision\": \"50097bb2ee7a2c17728af053df950ca4fe23398a\", \"got_v8_revision_cp\": \"refs/heads/8.5.183@{#1}\", \"got_webrtc_revision\": \"79ca92d952ae489e88cfac20058ab45d2f87368d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31545}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ee34734bf0231586c133ccdfe2dbeef93608163\", \"swarm_hashes\": {\"android_browsertests\": \"889d834428f301c94359d013e9d17dfbf069e86c\", \"chrome_public_test_apk\": \"6a9ed14a84846598a133f4efe895c9b40e273bf2\", \"content_browsertests\": \"c9e3845de58f65217b1814d45303de7a9fd60de1\", \"content_shell_test_apk\": \"579bd4be8bb0e9c7f0001b29ee66ec6465a2b75a\", \"content_unittests\": \"d062ba4c3a1efba0e900053ccb50f978c4310d66\", \"unit_tests\": \"ecb070066ed7343edd57cb992ee16e3753b5b78e\"}, \"swarm_hashes_refs/heads/master(at){#780047}_without_patch\": {\"android_browsertests\": \"889d834428f301c94359d013e9d17dfbf069e86c\", \"chrome_public_test_apk\": \"6a9ed14a84846598a133f4efe895c9b40e273bf2\", \"content_browsertests\": \"c9e3845de58f65217b1814d45303de7a9fd60de1\", \"content_shell_test_apk\": \"579bd4be8bb0e9c7f0001b29ee66ec6465a2b75a\", \"content_unittests\": \"d062ba4c3a1efba0e900053ccb50f978c4310d66\", \"unit_tests\": \"ecb070066ed7343edd57cb992ee16e3753b5b78e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_279625ee090c361ea8cf031374b7b020d1191c526e0ef1068ab634999c69ee70_v2\", \"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_v2\", \"builder_56aee418b4de09ccf28be71e6139f94d5975251175a488a48cea760f537675ad_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_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-91-ryts\"], \"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.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- ...122 more failure(s) (126 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- All/RenderFrameHostManagerDefaultProcessTest.NavigationRacesWithSitelessCommitInDefaultProcess/1\\n\\n- ...25 more failure(s) (27 total)...\\n\\n#### ...1 more test(s)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1592518826256955",
"status": "COMPLETED",
"status_changed_ts": "1592518827302238",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3423",
"builder:android-bfcache-rel",
"buildset:commit/git/1ee34734bf0231586c133ccdfe2dbeef93608163",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ee34734bf0231586c133ccdfe2dbeef93608163",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042546793727944960",
"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/8877116731558748320/+/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:4ce255f0792cf110",
"user_agent:luci-scheduler"
],
"updated_ts": "1592522828265828",
"url": "https://ci.chromium.org/b/8877116731558748320",
"utcnow_ts": "1592522829106741"
}
}
[23:27:09.118] Invocation finished in 1h7m18.991006885s with status FAILED