[01:03:46.508] New invocation is queued and will start shortly
[01:03:47.571] Starting the invocation (attempt 1)
[01:03:47.606] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:03:47.606] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:03:47.606] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020430819749921008",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5976a85bac31922f62d1947e6957f06fffe9fe1d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9020430819749921008",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"gitiles_ref:refs/heads/master"
]
}
[01:03:48.269] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1613610227701333",
"id": "8855000757836586768",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5976a85bac31922f62d1947e6957f06fffe9fe1d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613610228051077",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7874",
"builder:android-bfcache-rel",
"buildset:commit/git/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020430819749921008",
"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/8855000757836586768/+/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": "1613610228051197",
"url": "https://ci.chromium.org/b/8855000757836586768",
"utcnow_ts": "1613610228258870"
}
}
[01:03:48.269] Task URL: https://ci.chromium.org/b/8855000757836586768
[01:03:48.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:2:0) after 1m0s
[01:03:49.690] Received PubSub notification, asking Buildbucket for the build status
[01:03:49.760] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:48.284] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:2:0)
[01:04:48.284] Timer tick, asking Buildbucket for the build status
[01:04:48.437] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:48.437] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:4:0) after 1m0s
[01:05:48.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:4:0)
[01:05:48.493] Timer tick, asking Buildbucket for the build status
[01:05:48.668] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:48.668] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:5:0) after 1m0s
[01:06:48.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:5:0)
[01:06:48.685] Timer tick, asking Buildbucket for the build status
[01:06:48.764] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:48.764] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:6:0) after 1m0s
[01:07:48.785] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:6:0)
[01:07:48.785] Timer tick, asking Buildbucket for the build status
[01:07:48.924] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:48.924] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:7:0) after 1m0s
[01:08:48.985] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:7:0)
[01:08:48.985] Timer tick, asking Buildbucket for the build status
[01:08:49.149] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:49.149] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:8:0) after 1m0s
[01:09:49.139] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:8:0)
[01:09:49.139] Timer tick, asking Buildbucket for the build status
[01:09:49.254] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:49.254] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:9:0) after 1m0s
[01:10:49.230] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:9:0)
[01:10:49.230] Timer tick, asking Buildbucket for the build status
[01:10:49.385] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:49.386] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:10:0) after 1m0s
[01:11:49.480] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:10:0)
[01:11:49.480] Timer tick, asking Buildbucket for the build status
[01:11:49.561] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:49.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:11:0) after 1m0s
[01:12:49.579] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:11:0)
[01:12:49.579] Timer tick, asking Buildbucket for the build status
[01:12:49.672] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:49.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:12:0) after 1m0s
[01:13:49.717] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:12:0)
[01:13:49.717] Timer tick, asking Buildbucket for the build status
[01:13:49.849] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:49.849] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:13:0) after 1m0s
[01:14:49.889] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:13:0)
[01:14:49.889] Timer tick, asking Buildbucket for the build status
[01:14:50.117] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:50.117] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:14:0) after 1m0s
[01:15:50.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:14:0)
[01:15:50.312] Timer tick, asking Buildbucket for the build status
[01:15:50.382] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:50.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:15:0) after 1m0s
[01:16:50.458] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:15:0)
[01:16:50.458] Timer tick, asking Buildbucket for the build status
[01:16:50.535] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:50.535] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:16:0) after 1m0s
[01:17:50.606] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:16:0)
[01:17:50.606] Timer tick, asking Buildbucket for the build status
[01:17:50.707] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:50.707] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:17:0) after 1m0s
[01:18:50.917] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:17:0)
[01:18:50.917] Timer tick, asking Buildbucket for the build status
[01:18:51.072] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:51.072] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:18:0) after 1m0s
[01:19:51.117] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:18:0)
[01:19:51.117] Timer tick, asking Buildbucket for the build status
[01:19:51.196] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:51.196] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:19:0) after 1m0s
[01:20:51.350] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:19:0)
[01:20:51.350] Timer tick, asking Buildbucket for the build status
[01:20:51.433] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:51.433] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:20:0) after 1m0s
[01:21:51.625] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:20:0)
[01:21:51.625] Timer tick, asking Buildbucket for the build status
[01:21:51.698] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:51.698] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:21:0) after 1m0s
[01:22:51.722] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:21:0)
[01:22:51.722] Timer tick, asking Buildbucket for the build status
[01:22:51.792] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:51.792] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:22:0) after 1m0s
[01:23:51.908] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:22:0)
[01:23:51.908] Timer tick, asking Buildbucket for the build status
[01:23:52.020] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:52.020] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:23:0) after 1m0s
[01:24:52.136] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:23:0)
[01:24:52.136] Timer tick, asking Buildbucket for the build status
[01:24:52.440] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:52.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:24:0) after 1m0s
[01:25:52.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:24:0)
[01:25:52.465] Timer tick, asking Buildbucket for the build status
[01:25:52.529] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:52.529] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:25:0) after 1m0s
[01:26:52.552] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:25:0)
[01:26:52.552] Timer tick, asking Buildbucket for the build status
[01:26:52.657] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:52.657] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:26:0) after 1m0s
[01:27:52.675] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:26:0)
[01:27:52.675] Timer tick, asking Buildbucket for the build status
[01:27:52.754] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:52.754] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:27:0) after 1m0s
[01:28:52.838] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:27:0)
[01:28:52.838] Timer tick, asking Buildbucket for the build status
[01:28:52.952] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:52.952] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:28:0) after 1m0s
[01:29:52.988] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:28:0)
[01:29:52.988] Timer tick, asking Buildbucket for the build status
[01:29:53.193] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:53.193] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:29:0) after 1m0s
[01:30:53.279] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:29:0)
[01:30:53.279] Timer tick, asking Buildbucket for the build status
[01:30:53.393] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:53.393] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:30:0) after 1m0s
[01:31:53.584] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:30:0)
[01:31:53.584] Timer tick, asking Buildbucket for the build status
[01:31:53.733] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:53.733] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:31:0) after 1m0s
[01:32:53.744] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:31:0)
[01:32:53.744] Timer tick, asking Buildbucket for the build status
[01:32:53.829] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:53.829] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:32:0) after 1m0s
[01:33:53.881] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:32:0)
[01:33:53.881] Timer tick, asking Buildbucket for the build status
[01:33:54.010] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:54.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:33:0) after 1m0s
[01:34:54.101] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:33:0)
[01:34:54.101] Timer tick, asking Buildbucket for the build status
[01:34:54.174] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:54.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:34:0) after 1m0s
[01:35:54.262] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:34:0)
[01:35:54.262] Timer tick, asking Buildbucket for the build status
[01:35:54.344] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:54.344] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:35:0) after 1m0s
[01:36:54.402] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:35:0)
[01:36:54.402] Timer tick, asking Buildbucket for the build status
[01:36:54.810] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:54.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:36:0) after 1m0s
[01:37:54.965] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:36:0)
[01:37:54.965] Timer tick, asking Buildbucket for the build status
[01:37:55.079] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:55.079] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:37:0) after 1m0s
[01:38:55.140] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:37:0)
[01:38:55.140] Timer tick, asking Buildbucket for the build status
[01:38:55.284] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:55.284] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:38:0) after 1m0s
[01:39:55.270] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:38:0)
[01:39:55.270] Timer tick, asking Buildbucket for the build status
[01:39:55.372] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:55.372] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:39:0) after 1m0s
[01:40:55.374] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:39:0)
[01:40:55.374] Timer tick, asking Buildbucket for the build status
[01:40:55.561] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:55.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:40:0) after 1m0s
[01:41:55.604] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:40:0)
[01:41:55.604] Timer tick, asking Buildbucket for the build status
[01:41:55.702] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:55.702] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:41:0) after 1m0s
[01:42:55.692] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:41:0)
[01:42:55.692] Timer tick, asking Buildbucket for the build status
[01:42:55.768] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:55.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:42:0) after 1m0s
[01:43:55.787] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:42:0)
[01:43:55.787] Timer tick, asking Buildbucket for the build status
[01:43:55.880] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:55.880] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:43:0) after 1m0s
[01:44:55.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:43:0)
[01:44:55.941] Timer tick, asking Buildbucket for the build status
[01:44:56.022] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:56.022] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:44:0) after 1m0s
[01:45:56.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:44:0)
[01:45:56.191] Timer tick, asking Buildbucket for the build status
[01:45:56.412] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:56.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:45:0) after 1m0s
[01:46:56.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:45:0)
[01:46:56.460] Timer tick, asking Buildbucket for the build status
[01:46:56.541] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:56.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:46:0) after 1m0s
[01:47:56.584] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:46:0)
[01:47:56.585] Timer tick, asking Buildbucket for the build status
[01:47:56.658] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:56.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:47:0) after 1m0s
[01:48:56.694] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:47:0)
[01:48:56.694] Timer tick, asking Buildbucket for the build status
[01:48:56.810] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:48:0) after 1m0s
[01:49:56.829] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:48:0)
[01:49:56.829] Timer tick, asking Buildbucket for the build status
[01:49:56.925] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:56.925] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:49:0) after 1m0s
[01:50:56.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:49:0)
[01:50:56.943] Timer tick, asking Buildbucket for the build status
[01:50:57.103] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:57.103] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:50:0) after 1m0s
[01:51:57.127] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:50:0)
[01:51:57.127] Timer tick, asking Buildbucket for the build status
[01:51:57.204] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:57.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:51:0) after 1m0s
[01:52:57.202] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:51:0)
[01:52:57.202] Timer tick, asking Buildbucket for the build status
[01:52:57.291] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:57.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:52:0) after 1m0s
[01:53:57.320] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:52:0)
[01:53:57.320] Timer tick, asking Buildbucket for the build status
[01:53:57.500] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:57.500] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:53:0) after 1m0s
[01:54:57.591] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:53:0)
[01:54:57.591] Timer tick, asking Buildbucket for the build status
[01:54:57.724] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:57.724] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:54:0) after 1m0s
[01:55:57.836] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:54:0)
[01:55:57.836] Timer tick, asking Buildbucket for the build status
[01:55:57.916] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:57.916] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:55:0) after 1m0s
[01:56:58.045] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:55:0)
[01:56:58.045] Timer tick, asking Buildbucket for the build status
[01:56:58.130] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:58.130] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:56:0) after 1m0s
[01:57:58.215] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:56:0)
[01:57:58.215] Timer tick, asking Buildbucket for the build status
[01:57:58.305] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:57:0) after 1m0s
[01:58:58.347] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:57:0)
[01:58:58.347] Timer tick, asking Buildbucket for the build status
[01:58:58.808] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:58.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:58:0) after 1m0s
[01:59:58.847] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:58:0)
[01:59:58.847] Timer tick, asking Buildbucket for the build status
[01:59:58.927] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:58.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:59:0) after 1m0s
[02:00:59.015] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:59:0)
[02:00:59.015] Timer tick, asking Buildbucket for the build status
[02:00:59.458] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:59.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:60:0) after 1m0s
[02:01:59.502] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:60:0)
[02:01:59.502] Timer tick, asking Buildbucket for the build status
[02:01:59.634] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.634] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:61:0) after 1m0s
[02:02:59.697] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:61:0)
[02:02:59.697] Timer tick, asking Buildbucket for the build status
[02:02:59.934] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:62:0) after 1m0s
[02:03:59.967] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:62:0)
[02:03:59.967] Timer tick, asking Buildbucket for the build status
[02:04:00.099] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:00.099] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:63:0) after 1m0s
[02:05:00.448] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:63:0)
[02:05:00.448] Timer tick, asking Buildbucket for the build status
[02:05:00.531] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:00.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:64:0) after 1m0s
[02:06:00.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:64:0)
[02:06:00.685] Timer tick, asking Buildbucket for the build status
[02:06:00.765] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:00.765] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:65:0) after 1m0s
[02:07:01.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:65:0)
[02:07:01.039] Timer tick, asking Buildbucket for the build status
[02:07:01.122] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:01.122] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:66:0) after 1m0s
[02:08:01.192] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:66:0)
[02:08:01.192] Timer tick, asking Buildbucket for the build status
[02:08:01.287] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:01.287] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:67:0) after 1m0s
[02:09:01.327] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:67:0)
[02:09:01.327] Timer tick, asking Buildbucket for the build status
[02:09:01.484] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:01.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:68:0) after 1m0s
[02:10:01.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:68:0)
[02:10:01.567] Timer tick, asking Buildbucket for the build status
[02:10:01.712] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:01.712] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:69:0) after 1m0s
[02:11:01.830] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:69:0)
[02:11:01.831] Timer tick, asking Buildbucket for the build status
[02:11:02.034] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:02.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:70:0) after 1m0s
[02:12:02.145] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:70:0)
[02:12:02.145] Timer tick, asking Buildbucket for the build status
[02:12:02.228] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:02.228] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:71:0) after 1m0s
[02:13:02.253] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:71:0)
[02:13:02.253] Timer tick, asking Buildbucket for the build status
[02:13:02.331] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:02.331] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:72:0) after 1m0s
[02:14:02.394] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:72:0)
[02:14:02.394] Timer tick, asking Buildbucket for the build status
[02:14:02.507] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:73:0) after 1m0s
[02:15:02.618] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:73:0)
[02:15:02.618] Timer tick, asking Buildbucket for the build status
[02:15:02.704] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.705] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:74:0) after 1m0s
[02:16:02.758] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:74:0)
[02:16:02.758] Timer tick, asking Buildbucket for the build status
[02:16:03.022] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:03.022] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:75:0) after 1m0s
[02:17:03.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:75:0)
[02:17:03.079] Timer tick, asking Buildbucket for the build status
[02:17:03.161] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:03.161] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:76:0) after 1m0s
[02:18:03.214] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:76:0)
[02:18:03.214] Timer tick, asking Buildbucket for the build status
[02:18:03.291] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:03.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:77:0) after 1m0s
[02:19:03.348] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:77:0)
[02:19:03.348] Timer tick, asking Buildbucket for the build status
[02:19:03.526] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:03.526] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:78:0) after 1m0s
[02:20:03.576] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:78:0)
[02:20:03.576] Timer tick, asking Buildbucket for the build status
[02:20:03.831] Build 8855000757836586768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020430819749921008:79:0) after 1m0s
[02:20:10.511] Received PubSub notification, asking Buildbucket for the build status
[02:20:10.820] Build 8855000757836586768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:20:10.820] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613614808795632",
"created_by": "project:chromium",
"created_ts": "1613610227701333",
"id": "8855000757836586768",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5976a85bac31922f62d1947e6957f06fffe9fe1d\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"5976a85bac31922f62d1947e6957f06fffe9fe1d\", \"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-central1-b-8-166-b6ag\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7874, \"got_angle_revision\": \"0fa6c307ac7855a10ff95b661ca827add56e1576\", \"got_dawn_revision\": \"1b31dc0bb200dab1c7360b84fc15784eb81596c8\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"5976a85bac31922f62d1947e6957f06fffe9fe1d\", \"got_revision_cp\": \"refs/heads/master@{#855052}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"0320eab60f8aca74425ce5722283a3ff505c1737\", \"got_v8_revision\": \"f3a23e76a36beb47a379d5c4623c6738b50db810\", \"got_v8_revision_cp\": \"refs/heads/9.0.207@{#1}\", \"got_webrtc_revision\": \"ebc563e927b0ea1db816021e83b20150c116e573\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33287}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5976a85bac31922f62d1947e6957f06fffe9fe1d\", \"swarm_hashes_refs/heads/master(at){#855052}_without_patch\": {\"android_browsertests\": \"372c2abacfaffa6417e6b54f489e5166255d214d\", \"chrome_public_test_apk\": \"1c96ea6eb87243a170b0f2aeb6259fe835462658\", \"content_browsertests\": \"079dc0f808de083d0cfde7eedeff6ce56e053c0f\", \"content_shell_test_apk\": \"e88e0637db9a416ee256ce7992b0fec85b4db502\", \"content_unittests\": \"cd7a7dc3d561bf10f58ffb2761f913ac39af06ce\", \"unit_tests\": \"83e5f7004741de6ef8c2c04d844abc050aa8121d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02c50c25350278ea3e2feb85c748c248af9e0ccc93852ce717c8651b544f7a96_v2\", \"builder_1267e646917891f5f926cdb94ab3368f0c9399d68d76518d691afb23f68811c9_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-central1-b-8-166-b6ag\"], \"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.7.12\"], \"server_version\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613610228778061",
"status": "COMPLETED",
"status_changed_ts": "1613614809468633",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7874",
"builder:android-bfcache-rel",
"buildset:commit/git/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5976a85bac31922f62d1947e6957f06fffe9fe1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020430819749921008",
"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/8855000757836586768/+/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:51cb7ba2d3d90b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613614809473669",
"url": "https://ci.chromium.org/b/8855000757836586768",
"utcnow_ts": "1613614810798774"
}
}
[02:20:10.820] Invocation finished in 1h16m24.327326395s with status SUCCEEDED