[16:38:33.139] New invocation is queued and will start shortly
[16:38:34.288] Starting the invocation (attempt 1)
[16:38:34.335] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:38:34.335] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:38:34.336] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033417971819564688",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b9940a8e80a4f714bb81917d83730e6d3270a079\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9033417971819564688",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/b9940a8e80a4f714bb81917d83730e6d3270a079",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9940a8e80a4f714bb81917d83730e6d3270a079",
"gitiles_ref:refs/heads/master"
]
}
[16:38:35.615] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601224714435716",
"id": "8867987909794379664",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9940a8e80a4f714bb81917d83730e6d3270a079\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601224715366989",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5235",
"builder:android-bfcache-rel",
"buildset:commit/git/b9940a8e80a4f714bb81917d83730e6d3270a079",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9940a8e80a4f714bb81917d83730e6d3270a079",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033417971819564688",
"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/8867987909794379664/+/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": "1601224715367108",
"url": "https://ci.chromium.org/b/8867987909794379664",
"utcnow_ts": "1601224715599748"
}
}
[16:38:35.615] Task URL: https://ci.chromium.org/b/8867987909794379664
[16:38:35.615] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:2:0) after 1m0s
[16:39:35.718] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:2:0)
[16:39:35.718] Timer tick, asking Buildbucket for the build status
[16:39:36.108] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:36.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:3:0) after 1m0s
[16:40:36.196] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:3:0)
[16:40:36.196] Timer tick, asking Buildbucket for the build status
[16:40:36.289] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:36.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:4:0) after 1m0s
[16:41:36.337] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:4:0)
[16:41:36.337] Timer tick, asking Buildbucket for the build status
[16:41:36.405] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:36.405] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:5:0) after 1m0s
[16:42:36.496] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:5:0)
[16:42:36.496] Timer tick, asking Buildbucket for the build status
[16:42:36.635] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:36.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:6:0) after 1m0s
[16:43:36.668] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:6:0)
[16:43:36.668] Timer tick, asking Buildbucket for the build status
[16:43:36.778] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:43:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:7:0) after 1m0s
[16:44:36.896] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:7:0)
[16:44:36.896] Timer tick, asking Buildbucket for the build status
[16:44:36.991] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:36.991] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:8:0) after 1m0s
[16:45:37.031] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:8:0)
[16:45:37.031] Timer tick, asking Buildbucket for the build status
[16:45:37.157] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:37.157] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:9:0) after 1m0s
[16:46:37.370] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:9:0)
[16:46:37.370] Timer tick, asking Buildbucket for the build status
[16:46:37.459] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:37.459] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:10:0) after 1m0s
[16:47:37.580] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:10:0)
[16:47:37.580] Timer tick, asking Buildbucket for the build status
[16:47:37.683] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:37.683] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:11:0) after 1m0s
[16:48:37.911] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:11:0)
[16:48:37.911] Timer tick, asking Buildbucket for the build status
[16:48:38.114] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:38.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:12:0) after 1m0s
[16:49:38.260] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:12:0)
[16:49:38.260] Timer tick, asking Buildbucket for the build status
[16:49:38.337] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:38.337] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:13:0) after 1m0s
[16:50:38.340] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:13:0)
[16:50:38.340] Timer tick, asking Buildbucket for the build status
[16:50:38.411] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:50:38.411] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:14:0) after 1m0s
[16:51:38.497] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:14:0)
[16:51:38.497] Timer tick, asking Buildbucket for the build status
[16:51:38.592] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:51:38.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:15:0) after 1m0s
[16:52:38.668] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:15:0)
[16:52:38.668] Timer tick, asking Buildbucket for the build status
[16:52:39.166] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:39.166] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:16:0) after 1m0s
[16:53:39.189] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:16:0)
[16:53:39.189] Timer tick, asking Buildbucket for the build status
[16:53:39.311] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:39.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:17:0) after 1m0s
[16:54:39.336] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:17:0)
[16:54:39.336] Timer tick, asking Buildbucket for the build status
[16:54:39.406] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:39.406] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:18:0) after 1m0s
[16:55:39.454] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:18:0)
[16:55:39.454] Timer tick, asking Buildbucket for the build status
[16:55:39.528] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:39.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:19:0) after 1m0s
[16:56:39.593] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:19:0)
[16:56:39.593] Timer tick, asking Buildbucket for the build status
[16:56:39.669] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:39.669] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:20:0) after 1m0s
[16:57:39.730] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:20:0)
[16:57:39.730] Timer tick, asking Buildbucket for the build status
[16:57:39.831] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:39.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:21:0) after 1m0s
[16:58:39.938] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:21:0)
[16:58:39.938] Timer tick, asking Buildbucket for the build status
[16:58:40.057] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:58:40.057] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:22:0) after 1m0s
[16:59:40.137] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:22:0)
[16:59:40.137] Timer tick, asking Buildbucket for the build status
[16:59:40.204] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:40.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:23:0) after 1m0s
[17:00:40.358] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:23:0)
[17:00:40.358] Timer tick, asking Buildbucket for the build status
[17:00:40.445] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:00:40.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:24:0) after 1m0s
[17:01:40.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:24:0)
[17:01:40.603] Timer tick, asking Buildbucket for the build status
[17:01:40.699] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:01:40.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:25:0) after 1m0s
[17:02:40.731] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:25:0)
[17:02:40.731] Timer tick, asking Buildbucket for the build status
[17:02:40.844] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:02:40.844] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:26:0) after 1m0s
[17:03:40.979] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:26:0)
[17:03:40.979] Timer tick, asking Buildbucket for the build status
[17:03:41.101] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:03:41.101] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:27:0) after 1m0s
[17:04:41.205] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:27:0)
[17:04:41.205] Timer tick, asking Buildbucket for the build status
[17:04:41.290] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:04:41.290] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:28:0) after 1m0s
[17:05:41.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:28:0)
[17:05:41.465] Timer tick, asking Buildbucket for the build status
[17:05:41.678] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:05:41.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:29:0) after 1m0s
[17:06:41.914] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:29:0)
[17:06:41.914] Timer tick, asking Buildbucket for the build status
[17:06:41.992] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:06:41.992] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:30:0) after 1m0s
[17:07:42.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:30:0)
[17:07:42.129] Timer tick, asking Buildbucket for the build status
[17:07:42.237] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:07:42.237] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:31:0) after 1m0s
[17:08:42.352] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:31:0)
[17:08:42.352] Timer tick, asking Buildbucket for the build status
[17:08:42.457] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:08:42.457] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:32:0) after 1m0s
[17:09:42.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:32:0)
[17:09:42.519] Timer tick, asking Buildbucket for the build status
[17:09:42.600] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:09:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:33:0) after 1m0s
[17:10:42.587] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:33:0)
[17:10:42.587] Timer tick, asking Buildbucket for the build status
[17:10:42.744] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:10:42.744] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:34:0) after 1m0s
[17:11:42.748] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:34:0)
[17:11:42.748] Timer tick, asking Buildbucket for the build status
[17:11:42.961] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:11:42.961] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:35:0) after 1m0s
[17:12:42.981] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:35:0)
[17:12:42.981] Timer tick, asking Buildbucket for the build status
[17:12:43.056] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:12:43.056] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:36:0) after 1m0s
[17:13:43.065] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:36:0)
[17:13:43.065] Timer tick, asking Buildbucket for the build status
[17:13:43.211] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:13:43.211] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:37:0) after 1m0s
[17:14:43.320] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:37:0)
[17:14:43.320] Timer tick, asking Buildbucket for the build status
[17:14:43.440] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:14:43.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:38:0) after 1m0s
[17:15:43.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:38:0)
[17:15:43.609] Timer tick, asking Buildbucket for the build status
[17:15:43.692] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:15:43.692] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:39:0) after 1m0s
[17:16:43.783] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:39:0)
[17:16:43.783] Timer tick, asking Buildbucket for the build status
[17:16:43.931] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:16:43.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:40:0) after 1m0s
[17:17:44.047] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:40:0)
[17:17:44.047] Timer tick, asking Buildbucket for the build status
[17:17:44.131] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:17:44.131] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:41:0) after 1m0s
[17:18:44.204] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:41:0)
[17:18:44.204] Timer tick, asking Buildbucket for the build status
[17:18:44.348] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:18:44.348] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:42:0) after 1m0s
[17:19:44.447] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:42:0)
[17:19:44.447] Timer tick, asking Buildbucket for the build status
[17:19:44.533] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:19:44.533] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:43:0) after 1m0s
[17:20:44.808] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:43:0)
[17:20:44.808] Timer tick, asking Buildbucket for the build status
[17:20:44.883] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:20:44.883] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:44:0) after 1m0s
[17:21:45.068] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:44:0)
[17:21:45.068] Timer tick, asking Buildbucket for the build status
[17:21:45.300] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:45.300] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:45:0) after 1m0s
[17:22:45.462] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:45:0)
[17:22:45.462] Timer tick, asking Buildbucket for the build status
[17:22:45.569] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:45.569] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:46:0) after 1m0s
[17:23:45.807] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:46:0)
[17:23:45.807] Timer tick, asking Buildbucket for the build status
[17:23:46.082] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:46.082] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:47:0) after 1m0s
[17:24:46.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:47:0)
[17:24:46.061] Timer tick, asking Buildbucket for the build status
[17:24:46.174] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:46.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:48:0) after 1m0s
[17:25:46.251] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:48:0)
[17:25:46.251] Timer tick, asking Buildbucket for the build status
[17:25:46.345] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:46.345] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:49:0) after 1m0s
[17:26:46.754] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:49:0)
[17:26:46.754] Timer tick, asking Buildbucket for the build status
[17:26:46.843] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:46.843] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:50:0) after 1m0s
[17:27:46.922] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:50:0)
[17:27:46.922] Timer tick, asking Buildbucket for the build status
[17:27:47.005] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:27:47.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:51:0) after 1m0s
[17:28:47.116] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:51:0)
[17:28:47.116] Timer tick, asking Buildbucket for the build status
[17:28:47.243] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:28:47.243] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:52:0) after 1m0s
[17:29:47.357] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:52:0)
[17:29:47.357] Timer tick, asking Buildbucket for the build status
[17:29:47.442] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:29:47.442] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:53:0) after 1m0s
[17:30:47.471] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:53:0)
[17:30:47.471] Timer tick, asking Buildbucket for the build status
[17:30:47.572] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:47.572] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:54:0) after 1m0s
[17:31:47.735] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:54:0)
[17:31:47.735] Timer tick, asking Buildbucket for the build status
[17:31:47.809] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:47.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:55:0) after 1m0s
[17:32:47.861] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:55:0)
[17:32:47.861] Timer tick, asking Buildbucket for the build status
[17:32:48.096] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:48.096] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:56:0) after 1m0s
[17:33:48.163] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:56:0)
[17:33:48.163] Timer tick, asking Buildbucket for the build status
[17:33:48.287] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:48.287] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:57:0) after 1m0s
[17:34:48.370] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:57:0)
[17:34:48.370] Timer tick, asking Buildbucket for the build status
[17:34:48.522] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:48.522] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:58:0) after 1m0s
[17:35:48.575] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:58:0)
[17:35:48.575] Timer tick, asking Buildbucket for the build status
[17:35:48.767] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:48.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:59:0) after 1m0s
[17:36:48.823] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:59:0)
[17:36:48.823] Timer tick, asking Buildbucket for the build status
[17:36:48.933] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:48.933] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:60:0) after 1m0s
[17:37:49.011] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:60:0)
[17:37:49.011] Timer tick, asking Buildbucket for the build status
[17:37:49.156] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:49.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:61:0) after 1m0s
[17:38:49.254] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:61:0)
[17:38:49.254] Timer tick, asking Buildbucket for the build status
[17:38:49.810] Build 8867987909794379664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:49.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:62:0) after 1m0s
[17:39:22.857] Received PubSub notification, asking Buildbucket for the build status
[17:39:23.211] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:49.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:62:0)
[17:39:49.954] Timer tick, asking Buildbucket for the build status
[17:39:50.172] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:50.172] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:64:0) after 1m0s
[17:40:50.241] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:64:0)
[17:40:50.241] Timer tick, asking Buildbucket for the build status
[17:40:50.339] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:50.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:65:0) after 1m0s
[17:41:50.379] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:65:0)
[17:41:50.379] Timer tick, asking Buildbucket for the build status
[17:41:50.759] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:50.759] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:66:0) after 1m0s
[17:42:50.891] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:66:0)
[17:42:50.891] Timer tick, asking Buildbucket for the build status
[17:42:51.216] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:51.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:67:0) after 1m0s
[17:43:51.251] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:67:0)
[17:43:51.251] Timer tick, asking Buildbucket for the build status
[17:43:51.361] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:51.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:68:0) after 1m0s
[17:44:51.401] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:68:0)
[17:44:51.401] Timer tick, asking Buildbucket for the build status
[17:44:51.469] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:51.469] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:69:0) after 1m0s
[17:45:51.607] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:69:0)
[17:45:51.607] Timer tick, asking Buildbucket for the build status
[17:45:51.670] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:51.670] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:70:0) after 1m0s
[17:46:51.645] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:70:0)
[17:46:51.645] Timer tick, asking Buildbucket for the build status
[17:46:51.739] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:51.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:71:0) after 1m0s
[17:47:51.823] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:71:0)
[17:47:51.823] Timer tick, asking Buildbucket for the build status
[17:47:51.910] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:51.910] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:72:0) after 1m0s
[17:48:51.935] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:72:0)
[17:48:51.935] Timer tick, asking Buildbucket for the build status
[17:48:52.045] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:52.045] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:73:0) after 1m0s
[17:49:52.158] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:73:0)
[17:49:52.158] Timer tick, asking Buildbucket for the build status
[17:49:52.233] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:52.233] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:74:0) after 1m0s
[17:50:52.273] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:74:0)
[17:50:52.273] Timer tick, asking Buildbucket for the build status
[17:50:52.348] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:52.348] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:75:0) after 1m0s
[17:51:52.413] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:75:0)
[17:51:52.413] Timer tick, asking Buildbucket for the build status
[17:51:52.785] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:52.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:76:0) after 1m0s
[17:52:52.893] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:76:0)
[17:52:52.893] Timer tick, asking Buildbucket for the build status
[17:52:52.968] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:52.968] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:77:0) after 1m0s
[17:53:53.011] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:77:0)
[17:53:53.011] Timer tick, asking Buildbucket for the build status
[17:53:53.402] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:53.402] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:78:0) after 1m0s
[17:54:53.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:78:0)
[17:54:53.460] Timer tick, asking Buildbucket for the build status
[17:54:53.541] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:53.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:79:0) after 1m0s
[17:55:53.563] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:79:0)
[17:55:53.563] Timer tick, asking Buildbucket for the build status
[17:55:53.630] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:53.630] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:80:0) after 1m0s
[17:56:53.691] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:80:0)
[17:56:53.691] Timer tick, asking Buildbucket for the build status
[17:56:53.813] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:53.813] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:81:0) after 1m0s
[17:57:53.923] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:81:0)
[17:57:53.923] Timer tick, asking Buildbucket for the build status
[17:57:53.989] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:53.989] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:82:0) after 1m0s
[17:58:54.175] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:82:0)
[17:58:54.175] Timer tick, asking Buildbucket for the build status
[17:58:54.420] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:54.420] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:83:0) after 1m0s
[17:59:54.616] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:83:0)
[17:59:54.616] Timer tick, asking Buildbucket for the build status
[17:59:54.683] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:54.683] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:84:0) after 1m0s
[18:00:54.739] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:84:0)
[18:00:54.739] Timer tick, asking Buildbucket for the build status
[18:00:55.019] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:55.019] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:85:0) after 1m0s
[18:01:55.236] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:85:0)
[18:01:55.236] Timer tick, asking Buildbucket for the build status
[18:01:55.303] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:55.303] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:86:0) after 1m0s
[18:02:55.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:86:0)
[18:02:55.455] Timer tick, asking Buildbucket for the build status
[18:02:55.862] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:55.862] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:87:0) after 1m0s
[18:03:55.896] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:87:0)
[18:03:55.896] Timer tick, asking Buildbucket for the build status
[18:03:56.044] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:56.044] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:88:0) after 1m0s
[18:04:56.144] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:88:0)
[18:04:56.144] Timer tick, asking Buildbucket for the build status
[18:04:56.417] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:56.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:89:0) after 1m0s
[18:05:56.499] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:89:0)
[18:05:56.499] Timer tick, asking Buildbucket for the build status
[18:05:56.734] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:56.734] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:90:0) after 1m0s
[18:06:56.759] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:90:0)
[18:06:56.759] Timer tick, asking Buildbucket for the build status
[18:06:56.832] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:56.832] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:91:0) after 1m0s
[18:07:56.832] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:91:0)
[18:07:56.832] Timer tick, asking Buildbucket for the build status
[18:07:56.898] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:56.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:92:0) after 1m0s
[18:08:56.962] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:92:0)
[18:08:56.962] Timer tick, asking Buildbucket for the build status
[18:08:57.197] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:57.197] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:93:0) after 1m0s
[18:09:57.268] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:93:0)
[18:09:57.268] Timer tick, asking Buildbucket for the build status
[18:09:57.367] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:57.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:94:0) after 1m0s
[18:10:57.549] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:94:0)
[18:10:57.549] Timer tick, asking Buildbucket for the build status
[18:10:57.700] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:57.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:95:0) after 1m0s
[18:11:57.692] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:95:0)
[18:11:57.692] Timer tick, asking Buildbucket for the build status
[18:11:57.773] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:57.773] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:96:0) after 1m0s
[18:12:57.857] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:96:0)
[18:12:57.857] Timer tick, asking Buildbucket for the build status
[18:12:57.935] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:57.935] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:97:0) after 1m0s
[18:13:57.971] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:97:0)
[18:13:57.971] Timer tick, asking Buildbucket for the build status
[18:13:58.083] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:58.083] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:98:0) after 1m0s
[18:14:58.185] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:98:0)
[18:14:58.186] Timer tick, asking Buildbucket for the build status
[18:14:58.248] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:58.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:99:0) after 1m0s
[18:15:58.293] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:99:0)
[18:15:58.293] Timer tick, asking Buildbucket for the build status
[18:15:58.363] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:100:0) after 1m0s
[18:16:58.456] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:100:0)
[18:16:58.456] Timer tick, asking Buildbucket for the build status
[18:16:58.834] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:58.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:101:0) after 1m0s
[18:17:58.870] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:101:0)
[18:17:58.870] Timer tick, asking Buildbucket for the build status
[18:17:59.354] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:59.354] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:102:0) after 1m0s
[18:19:00.026] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:102:0)
[18:19:00.026] Timer tick, asking Buildbucket for the build status
[18:19:00.403] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:00.403] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:103:0) after 1m0s
[18:20:00.481] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:103:0)
[18:20:00.481] Timer tick, asking Buildbucket for the build status
[18:20:00.557] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:00.557] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:104:0) after 1m0s
[18:21:00.670] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:104:0)
[18:21:00.670] Timer tick, asking Buildbucket for the build status
[18:21:00.776] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:00.776] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:105:0) after 1m0s
[18:22:00.845] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:105:0)
[18:22:00.845] Timer tick, asking Buildbucket for the build status
[18:22:00.939] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:00.939] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:106:0) after 1m0s
[18:23:00.979] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:106:0)
[18:23:00.979] Timer tick, asking Buildbucket for the build status
[18:23:01.047] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:01.047] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:107:0) after 1m0s
[18:24:01.218] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:107:0)
[18:24:01.218] Timer tick, asking Buildbucket for the build status
[18:24:01.544] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:01.544] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:108:0) after 1m0s
[18:25:01.589] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:108:0)
[18:25:01.589] Timer tick, asking Buildbucket for the build status
[18:25:01.656] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:01.656] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:109:0) after 1m0s
[18:26:01.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:109:0)
[18:26:01.869] Timer tick, asking Buildbucket for the build status
[18:26:01.941] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:01.941] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:110:0) after 1m0s
[18:27:02.072] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:110:0)
[18:27:02.072] Timer tick, asking Buildbucket for the build status
[18:27:02.193] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:02.193] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:111:0) after 1m0s
[18:28:02.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:111:0)
[18:28:02.286] Timer tick, asking Buildbucket for the build status
[18:28:02.364] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:02.364] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:112:0) after 1m0s
[18:29:02.457] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:112:0)
[18:29:02.457] Timer tick, asking Buildbucket for the build status
[18:29:02.592] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:02.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:113:0) after 1m0s
[18:30:02.656] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:113:0)
[18:30:02.656] Timer tick, asking Buildbucket for the build status
[18:30:02.737] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:02.737] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:114:0) after 1m0s
[18:31:02.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:114:0)
[18:31:02.791] Timer tick, asking Buildbucket for the build status
[18:31:03.085] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:03.085] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:115:0) after 1m0s
[18:32:03.181] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:115:0)
[18:32:03.181] Timer tick, asking Buildbucket for the build status
[18:32:03.507] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:03.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:116:0) after 1m0s
[18:33:03.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:116:0)
[18:33:03.681] Timer tick, asking Buildbucket for the build status
[18:33:03.747] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:03.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:117:0) after 1m0s
[18:34:03.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:117:0)
[18:34:03.770] Timer tick, asking Buildbucket for the build status
[18:34:03.931] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:03.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:118:0) after 1m0s
[18:35:03.966] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:118:0)
[18:35:03.966] Timer tick, asking Buildbucket for the build status
[18:35:04.035] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:04.035] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:119:0) after 1m0s
[18:36:04.199] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:119:0)
[18:36:04.199] Timer tick, asking Buildbucket for the build status
[18:36:04.291] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:04.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:120:0) after 1m0s
[18:37:04.491] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:120:0)
[18:37:04.491] Timer tick, asking Buildbucket for the build status
[18:37:04.832] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:04.832] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:121:0) after 1m0s
[18:38:04.898] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:121:0)
[18:38:04.898] Timer tick, asking Buildbucket for the build status
[18:38:05.023] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:05.023] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:122:0) after 1m0s
[18:39:05.109] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:122:0)
[18:39:05.109] Timer tick, asking Buildbucket for the build status
[18:39:05.218] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:05.218] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:123:0) after 1m0s
[18:40:05.249] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:123:0)
[18:40:05.249] Timer tick, asking Buildbucket for the build status
[18:40:05.323] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:05.323] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:124:0) after 1m0s
[18:41:05.369] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:124:0)
[18:41:05.369] Timer tick, asking Buildbucket for the build status
[18:41:05.434] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:05.434] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:125:0) after 1m0s
[18:42:05.507] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:125:0)
[18:42:05.507] Timer tick, asking Buildbucket for the build status
[18:42:05.590] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:05.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:126:0) after 1m0s
[18:43:05.659] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:126:0)
[18:43:05.659] Timer tick, asking Buildbucket for the build status
[18:43:05.743] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:05.743] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:127:0) after 1m0s
[18:44:05.802] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:127:0)
[18:44:05.802] Timer tick, asking Buildbucket for the build status
[18:44:06.030] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:06.030] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:128:0) after 1m0s
[18:45:06.089] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:128:0)
[18:45:06.089] Timer tick, asking Buildbucket for the build status
[18:45:06.157] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:06.157] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:129:0) after 1m0s
[18:46:06.150] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:129:0)
[18:46:06.150] Timer tick, asking Buildbucket for the build status
[18:46:06.980] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:06.980] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:130:0) after 1m0s
[18:47:07.041] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:130:0)
[18:47:07.041] Timer tick, asking Buildbucket for the build status
[18:47:07.139] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:07.139] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:131:0) after 1m0s
[18:48:07.193] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:131:0)
[18:48:07.193] Timer tick, asking Buildbucket for the build status
[18:48:07.271] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:07.271] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:132:0) after 1m0s
[18:49:07.348] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:132:0)
[18:49:07.348] Timer tick, asking Buildbucket for the build status
[18:49:07.466] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:07.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:133:0) after 1m0s
[18:50:07.545] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:133:0)
[18:50:07.545] Timer tick, asking Buildbucket for the build status
[18:50:07.698] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:07.698] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:134:0) after 1m0s
[18:51:07.797] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:134:0)
[18:51:07.797] Timer tick, asking Buildbucket for the build status
[18:51:07.976] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:07.976] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:135:0) after 1m0s
[18:52:08.187] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:135:0)
[18:52:08.187] Timer tick, asking Buildbucket for the build status
[18:52:08.467] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:08.467] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:136:0) after 1m0s
[18:53:08.549] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:136:0)
[18:53:08.549] Timer tick, asking Buildbucket for the build status
[18:53:08.649] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:08.649] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:137:0) after 1m0s
[18:54:08.705] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:137:0)
[18:54:08.705] Timer tick, asking Buildbucket for the build status
[18:54:08.848] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:08.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:138:0) after 1m0s
[18:55:08.942] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:138:0)
[18:55:08.942] Timer tick, asking Buildbucket for the build status
[18:55:09.612] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:09.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:139:0) after 1m0s
[18:56:09.678] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:139:0)
[18:56:09.678] Timer tick, asking Buildbucket for the build status
[18:56:09.879] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:140:0) after 1m0s
[18:57:09.907] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:140:0)
[18:57:09.907] Timer tick, asking Buildbucket for the build status
[18:57:10.021] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:10.021] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:141:0) after 1m0s
[18:58:10.202] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:141:0)
[18:58:10.202] Timer tick, asking Buildbucket for the build status
[18:58:10.582] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:10.582] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:142:0) after 1m0s
[18:59:10.653] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:142:0)
[18:59:10.653] Timer tick, asking Buildbucket for the build status
[18:59:10.768] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:10.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:143:0) after 1m0s
[19:00:10.841] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:143:0)
[19:00:10.841] Timer tick, asking Buildbucket for the build status
[19:00:10.942] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:10.943] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:144:0) after 1m0s
[19:01:10.995] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:144:0)
[19:01:10.995] Timer tick, asking Buildbucket for the build status
[19:01:11.097] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:11.097] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:145:0) after 1m0s
[19:02:11.161] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:145:0)
[19:02:11.161] Timer tick, asking Buildbucket for the build status
[19:02:11.297] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:11.297] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:146:0) after 1m0s
[19:03:11.335] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:146:0)
[19:03:11.335] Timer tick, asking Buildbucket for the build status
[19:03:11.449] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:11.449] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:147:0) after 1m0s
[19:04:11.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:147:0)
[19:04:11.519] Timer tick, asking Buildbucket for the build status
[19:04:11.650] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:11.650] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:148:0) after 1m0s
[19:05:11.660] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:148:0)
[19:05:11.660] Timer tick, asking Buildbucket for the build status
[19:05:11.797] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:11.797] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:149:0) after 1m0s
[19:06:11.873] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:149:0)
[19:06:11.873] Timer tick, asking Buildbucket for the build status
[19:06:11.975] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:11.975] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:150:0) after 1m0s
[19:07:12.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:150:0)
[19:07:12.036] Timer tick, asking Buildbucket for the build status
[19:07:12.128] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:12.128] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:151:0) after 1m0s
[19:08:12.243] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:151:0)
[19:08:12.243] Timer tick, asking Buildbucket for the build status
[19:08:12.317] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:12.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:152:0) after 1m0s
[19:09:12.370] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:152:0)
[19:09:12.370] Timer tick, asking Buildbucket for the build status
[19:09:12.594] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:12.594] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:153:0) after 1m0s
[19:10:12.686] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:153:0)
[19:10:12.686] Timer tick, asking Buildbucket for the build status
[19:10:12.773] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:12.773] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:154:0) after 1m0s
[19:11:12.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:154:0)
[19:11:12.791] Timer tick, asking Buildbucket for the build status
[19:11:13.229] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:13.229] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:155:0) after 1m0s
[19:12:13.347] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:155:0)
[19:12:13.347] Timer tick, asking Buildbucket for the build status
[19:12:13.473] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:13.473] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:156:0) after 1m0s
[19:13:13.533] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:156:0)
[19:13:13.533] Timer tick, asking Buildbucket for the build status
[19:13:13.666] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:157:0) after 1m0s
[19:14:13.721] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:157:0)
[19:14:13.721] Timer tick, asking Buildbucket for the build status
[19:14:14.511] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:14.511] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:158:0) after 1m0s
[19:15:14.535] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:158:0)
[19:15:14.535] Timer tick, asking Buildbucket for the build status
[19:15:14.596] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:14.596] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:159:0) after 1m0s
[19:16:14.658] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:159:0)
[19:16:14.658] Timer tick, asking Buildbucket for the build status
[19:16:15.003] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:15.003] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:160:0) after 1m0s
[19:17:15.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:160:0)
[19:17:15.129] Timer tick, asking Buildbucket for the build status
[19:17:15.925] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:15.925] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:161:0) after 1m0s
[19:18:15.914] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:161:0)
[19:18:15.914] Timer tick, asking Buildbucket for the build status
[19:18:15.983] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:15.983] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:162:0) after 1m0s
[19:19:15.965] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:162:0)
[19:19:15.965] Timer tick, asking Buildbucket for the build status
[19:19:16.120] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:16.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:163:0) after 1m0s
[19:20:16.160] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:163:0)
[19:20:16.160] Timer tick, asking Buildbucket for the build status
[19:20:16.267] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:164:0) after 1m0s
[19:21:16.423] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:164:0)
[19:21:16.423] Timer tick, asking Buildbucket for the build status
[19:21:16.622] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:16.622] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:165:0) after 1m0s
[19:22:16.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:165:0)
[19:22:16.706] Timer tick, asking Buildbucket for the build status
[19:22:16.796] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:16.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:166:0) after 1m0s
[19:23:16.950] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:166:0)
[19:23:16.950] Timer tick, asking Buildbucket for the build status
[19:23:17.239] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:17.239] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:167:0) after 1m0s
[19:24:17.500] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:167:0)
[19:24:17.500] Timer tick, asking Buildbucket for the build status
[19:24:17.610] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:17.610] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:168:0) after 1m0s
[19:25:17.708] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:168:0)
[19:25:17.708] Timer tick, asking Buildbucket for the build status
[19:25:17.782] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:17.782] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:169:0) after 1m0s
[19:26:17.850] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:169:0)
[19:26:17.850] Timer tick, asking Buildbucket for the build status
[19:26:17.915] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:17.915] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:170:0) after 1m0s
[19:27:18.022] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:170:0)
[19:27:18.022] Timer tick, asking Buildbucket for the build status
[19:27:18.086] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:18.086] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:171:0) after 1m0s
[19:28:18.135] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:171:0)
[19:28:18.135] Timer tick, asking Buildbucket for the build status
[19:28:18.205] Build 8867987909794379664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:18.205] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033417971819564688:172:0) after 1m0s
[19:29:03.869] Received PubSub notification, asking Buildbucket for the build status
[19:29:04.500] Build 8867987909794379664: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:29:04.500] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601234943220183",
"created_by": "project:chromium",
"created_ts": "1601224714435716",
"failure_reason": "BUILD_FAILURE",
"id": "8867987909794379664",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9940a8e80a4f714bb81917d83730e6d3270a079\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testLoggedEventId\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testAppMenuSuppressedWhenMaximized__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableLongpress\\n\\n- ...10 more failure(s) (14 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"b9940a8e80a4f714bb81917d83730e6d3270a079\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-40-quw7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5235, \"got_angle_revision\": \"6f521921351eb8bfbc30ec7f2aa0a1253b02f72c\", \"got_dawn_revision\": \"ed2b465f860720be30eeba35a56088dc5ba66d46\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"b9940a8e80a4f714bb81917d83730e6d3270a079\", \"got_revision_cp\": \"refs/heads/master@{#811057}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"b94db86cf9b3369ebd3c99ffe0d4072bac0a7b75\", \"got_v8_revision\": \"e79440e729c6f43073a53dfa37e970370481b619\", \"got_v8_revision_cp\": \"refs/heads/8.7.203@{#1}\", \"got_webrtc_revision\": \"111de34102c7ab137a512796356c3683e54f636e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32205}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9940a8e80a4f714bb81917d83730e6d3270a079\", \"swarm_hashes\": {\"android_browsertests\": \"fccb40ac7514d8767fce6b6d6ba580ac01b16580\", \"chrome_public_test_apk\": \"1fe8828729b47c080360f91ac23736f7551a6a73\", \"content_browsertests\": \"008682e4cebc26d9b81bb6f87215701900e8f31b\", \"content_shell_test_apk\": \"27049c17caddd7516516a77e6eaa1962a01ad4d2\", \"content_unittests\": \"6afde31ca5f3ad2fffa5167b5163aa2a2b884c36\", \"unit_tests\": \"12b286dfda4fdf21dbaf84c4cc5ad428645a0c9b\"}, \"swarm_hashes_refs/heads/master(at){#811057}_without_patch\": {\"android_browsertests\": \"fccb40ac7514d8767fce6b6d6ba580ac01b16580\", \"chrome_public_test_apk\": \"1fe8828729b47c080360f91ac23736f7551a6a73\", \"content_browsertests\": \"008682e4cebc26d9b81bb6f87215701900e8f31b\", \"content_shell_test_apk\": \"27049c17caddd7516516a77e6eaa1962a01ad4d2\", \"content_unittests\": \"6afde31ca5f3ad2fffa5167b5163aa2a2b884c36\", \"unit_tests\": \"12b286dfda4fdf21dbaf84c4cc5ad428645a0c9b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1cd278df517da7cab651119f0a33d220dc3926a519bcf9f00a4d0d15ccc0b43e_v2\", \"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_33498e86c0181efb6d75c3f73059d9522f6d5d2b3b1c7a2dc2a1c6b75cd205cd_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_9d393496fc50bd2ba7e16167b6377b317d5cb146b2dfdb60405146db5a374eac_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_d78b729059d6d7166c1c60b6c63641496a1541bf700cb46d40203a280149f161_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-40-quw7\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"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\": [\"5541-5d998a0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testLoggedEventId\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testAppMenuSuppressedWhenMaximized__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableLongpress\\n\\n- ...10 more failure(s) (14 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601228361184852",
"status": "COMPLETED",
"status_changed_ts": "1601228362407651",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5235",
"builder:android-bfcache-rel",
"buildset:commit/git/b9940a8e80a4f714bb81917d83730e6d3270a079",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9940a8e80a4f714bb81917d83730e6d3270a079",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033417971819564688",
"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/8867987909794379664/+/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:4ee93fb619641810",
"user_agent:luci-scheduler"
],
"updated_ts": "1601234943554499",
"url": "https://ci.chromium.org/b/8867987909794379664",
"utcnow_ts": "1601234944408083"
}
}
[19:29:04.501] Invocation finished in 2h50m31.379241318s with status FAILED