[17:30:21.116] New invocation is queued and will start shortly
[17:30:22.189] Starting the invocation (attempt 1)
[17:30:22.237] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:30:22.237] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:30:22.237] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023177255720783840",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2da48363c639636ea8e4f77a5e5e33c51475205c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9023177255720783840",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/2da48363c639636ea8e4f77a5e5e33c51475205c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da48363c639636ea8e4f77a5e5e33c51475205c",
"gitiles_ref:refs/heads/master"
]
}
[17:30:22.919] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610991022321206",
"id": "8857747193736995408",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2da48363c639636ea8e4f77a5e5e33c51475205c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610991022737541",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7329",
"builder:android-bfcache-rel",
"buildset:commit/git/2da48363c639636ea8e4f77a5e5e33c51475205c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da48363c639636ea8e4f77a5e5e33c51475205c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023177255720783840",
"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/8857747193736995408/+/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": "1610991022737688",
"url": "https://ci.chromium.org/b/8857747193736995408",
"utcnow_ts": "1610991022909027"
}
}
[17:30:22.919] Task URL: https://ci.chromium.org/b/8857747193736995408
[17:30:22.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:2:0) after 1m0s
[17:30:44.889] Received PubSub notification, asking Buildbucket for the build status
[17:30:44.950] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:23.023] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:2:0)
[17:31:23.023] Timer tick, asking Buildbucket for the build status
[17:31:23.124] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:23.124] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:4:0) after 1m0s
[17:32:23.353] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:4:0)
[17:32:23.353] Timer tick, asking Buildbucket for the build status
[17:32:23.578] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:23.578] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:5:0) after 1m0s
[17:33:23.667] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:5:0)
[17:33:23.668] Timer tick, asking Buildbucket for the build status
[17:33:23.740] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:23.740] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:6:0) after 1m0s
[17:34:23.720] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:6:0)
[17:34:23.720] Timer tick, asking Buildbucket for the build status
[17:34:24.076] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:24.076] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:7:0) after 1m0s
[17:35:24.276] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:7:0)
[17:35:24.276] Timer tick, asking Buildbucket for the build status
[17:35:24.392] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:8:0) after 1m0s
[17:36:24.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:8:0)
[17:36:24.455] Timer tick, asking Buildbucket for the build status
[17:36:25.480] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:25.480] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:9:0) after 1m0s
[17:37:25.698] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:9:0)
[17:37:25.698] Timer tick, asking Buildbucket for the build status
[17:37:25.837] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:25.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:10:0) after 1m0s
[17:38:25.899] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:10:0)
[17:38:25.899] Timer tick, asking Buildbucket for the build status
[17:38:26.019] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:26.019] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:11:0) after 1m0s
[17:39:26.117] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:11:0)
[17:39:26.117] Timer tick, asking Buildbucket for the build status
[17:39:26.224] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:26.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:12:0) after 1m0s
[17:40:26.246] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:12:0)
[17:40:26.246] Timer tick, asking Buildbucket for the build status
[17:40:26.496] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:26.496] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:13:0) after 1m0s
[17:41:26.531] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:13:0)
[17:41:26.531] Timer tick, asking Buildbucket for the build status
[17:41:26.601] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:26.601] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:14:0) after 1m0s
[17:42:26.657] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:14:0)
[17:42:26.657] Timer tick, asking Buildbucket for the build status
[17:42:27.018] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:27.018] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:15:0) after 1m0s
[17:43:27.062] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:15:0)
[17:43:27.062] Timer tick, asking Buildbucket for the build status
[17:43:27.172] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:27.172] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:16:0) after 1m0s
[17:44:27.190] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:16:0)
[17:44:27.190] Timer tick, asking Buildbucket for the build status
[17:44:27.303] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:27.303] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:17:0) after 1m0s
[17:45:27.361] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:17:0)
[17:45:27.361] Timer tick, asking Buildbucket for the build status
[17:45:27.480] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:27.480] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:18:0) after 1m0s
[17:46:27.602] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:18:0)
[17:46:27.602] Timer tick, asking Buildbucket for the build status
[17:46:27.831] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:27.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:19:0) after 1m0s
[17:47:27.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:19:0)
[17:47:27.915] Timer tick, asking Buildbucket for the build status
[17:47:28.245] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:28.245] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:20:0) after 1m0s
[17:48:28.377] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:20:0)
[17:48:28.377] Timer tick, asking Buildbucket for the build status
[17:48:28.447] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:28.447] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:21:0) after 1m0s
[17:49:28.461] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:21:0)
[17:49:28.461] Timer tick, asking Buildbucket for the build status
[17:49:28.564] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:28.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:22:0) after 1m0s
[17:50:28.674] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:22:0)
[17:50:28.674] Timer tick, asking Buildbucket for the build status
[17:50:28.785] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:28.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:23:0) after 1m0s
[17:51:28.842] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:23:0)
[17:51:28.842] Timer tick, asking Buildbucket for the build status
[17:51:28.919] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:28.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:24:0) after 1m0s
[17:52:28.981] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:24:0)
[17:52:28.981] Timer tick, asking Buildbucket for the build status
[17:52:29.051] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:29.051] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:25:0) after 1m0s
[17:53:29.074] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:25:0)
[17:53:29.074] Timer tick, asking Buildbucket for the build status
[17:53:29.171] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:29.171] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:26:0) after 1m0s
[17:54:30.921] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:26:0)
[17:54:30.921] Timer tick, asking Buildbucket for the build status
[17:54:31.141] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:31.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:27:0) after 1m0s
[17:55:32.469] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:27:0)
[17:55:32.469] Timer tick, asking Buildbucket for the build status
[17:55:32.570] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:32.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:28:0) after 1m0s
[17:56:32.632] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:28:0)
[17:56:32.632] Timer tick, asking Buildbucket for the build status
[17:56:32.721] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:32.721] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:29:0) after 1m0s
[17:57:32.759] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:29:0)
[17:57:32.759] Timer tick, asking Buildbucket for the build status
[17:57:32.876] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:32.876] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:30:0) after 1m0s
[17:58:32.937] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:30:0)
[17:58:32.937] Timer tick, asking Buildbucket for the build status
[17:58:33.010] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:33.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:31:0) after 1m0s
[17:59:33.105] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:31:0)
[17:59:33.105] Timer tick, asking Buildbucket for the build status
[17:59:33.194] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:33.194] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:32:0) after 1m0s
[18:00:33.238] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:32:0)
[18:00:33.238] Timer tick, asking Buildbucket for the build status
[18:00:34.202] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:34.202] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:33:0) after 1m0s
[18:01:34.420] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:33:0)
[18:01:34.420] Timer tick, asking Buildbucket for the build status
[18:01:34.499] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:34.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:34:0) after 1m0s
[18:02:34.728] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:34:0)
[18:02:34.728] Timer tick, asking Buildbucket for the build status
[18:02:34.806] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:34.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:35:0) after 1m0s
[18:03:34.861] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:35:0)
[18:03:34.861] Timer tick, asking Buildbucket for the build status
[18:03:34.947] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:34.947] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:36:0) after 1m0s
[18:04:35.071] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:36:0)
[18:04:35.071] Timer tick, asking Buildbucket for the build status
[18:04:35.191] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:35.191] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:37:0) after 1m0s
[18:05:35.433] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:37:0)
[18:05:35.433] Timer tick, asking Buildbucket for the build status
[18:05:35.758] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:35.758] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:38:0) after 1m0s
[18:06:35.839] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:38:0)
[18:06:35.839] Timer tick, asking Buildbucket for the build status
[18:06:35.914] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:35.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:39:0) after 1m0s
[18:07:36.067] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:39:0)
[18:07:36.067] Timer tick, asking Buildbucket for the build status
[18:07:36.141] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:36.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:40:0) after 1m0s
[18:08:36.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:40:0)
[18:08:36.428] Timer tick, asking Buildbucket for the build status
[18:08:36.514] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:36.514] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:41:0) after 1m0s
[18:09:36.700] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:41:0)
[18:09:36.700] Timer tick, asking Buildbucket for the build status
[18:09:36.800] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:36.800] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:42:0) after 1m0s
[18:10:36.972] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:42:0)
[18:10:36.972] Timer tick, asking Buildbucket for the build status
[18:10:37.224] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:37.225] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:43:0) after 1m0s
[18:11:37.380] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:43:0)
[18:11:37.380] Timer tick, asking Buildbucket for the build status
[18:11:37.481] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:37.481] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:44:0) after 1m0s
[18:12:37.468] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:44:0)
[18:12:37.468] Timer tick, asking Buildbucket for the build status
[18:12:37.548] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:37.548] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:45:0) after 1m0s
[18:13:37.534] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:45:0)
[18:13:37.534] Timer tick, asking Buildbucket for the build status
[18:13:37.621] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:37.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:46:0) after 1m0s
[18:14:37.648] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:46:0)
[18:14:37.648] Timer tick, asking Buildbucket for the build status
[18:14:37.719] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:37.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:47:0) after 1m0s
[18:15:37.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:47:0)
[18:15:37.757] Timer tick, asking Buildbucket for the build status
[18:15:37.909] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:37.909] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:48:0) after 1m0s
[18:16:37.969] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:48:0)
[18:16:37.969] Timer tick, asking Buildbucket for the build status
[18:16:38.050] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.050] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:49:0) after 1m0s
[18:17:38.137] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:49:0)
[18:17:38.137] Timer tick, asking Buildbucket for the build status
[18:17:38.256] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:38.256] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:50:0) after 1m0s
[18:18:38.358] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:50:0)
[18:18:38.358] Timer tick, asking Buildbucket for the build status
[18:18:38.990] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:38.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:51:0) after 1m0s
[18:19:39.012] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:51:0)
[18:19:39.012] Timer tick, asking Buildbucket for the build status
[18:19:39.538] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:39.538] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:52:0) after 1m0s
[18:20:39.579] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:52:0)
[18:20:39.579] Timer tick, asking Buildbucket for the build status
[18:20:40.063] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:40.063] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:53:0) after 1m0s
[18:21:40.119] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:53:0)
[18:21:40.119] Timer tick, asking Buildbucket for the build status
[18:21:40.248] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:40.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:54:0) after 1m0s
[18:22:40.275] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:54:0)
[18:22:40.275] Timer tick, asking Buildbucket for the build status
[18:22:40.600] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:40.600] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:55:0) after 1m0s
[18:23:40.665] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:55:0)
[18:23:40.665] Timer tick, asking Buildbucket for the build status
[18:23:40.739] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:40.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:56:0) after 1m0s
[18:24:40.773] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:56:0)
[18:24:40.773] Timer tick, asking Buildbucket for the build status
[18:24:40.900] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:40.900] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:57:0) after 1m0s
[18:25:40.961] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:57:0)
[18:25:40.961] Timer tick, asking Buildbucket for the build status
[18:25:41.176] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:41.176] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:58:0) after 1m0s
[18:26:41.159] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:58:0)
[18:26:41.159] Timer tick, asking Buildbucket for the build status
[18:26:41.428] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:41.428] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:59:0) after 1m0s
[18:27:41.489] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:59:0)
[18:27:41.489] Timer tick, asking Buildbucket for the build status
[18:27:41.823] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:41.823] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:60:0) after 1m0s
[18:28:41.912] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:60:0)
[18:28:41.912] Timer tick, asking Buildbucket for the build status
[18:28:42.342] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:42.342] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:61:0) after 1m0s
[18:29:42.424] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:61:0)
[18:29:42.424] Timer tick, asking Buildbucket for the build status
[18:29:42.806] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:62:0) after 1m0s
[18:30:42.835] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:62:0)
[18:30:42.835] Timer tick, asking Buildbucket for the build status
[18:30:42.996] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:42.996] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:63:0) after 1m0s
[18:31:43.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:63:0)
[18:31:43.034] Timer tick, asking Buildbucket for the build status
[18:31:43.418] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:43.418] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:64:0) after 1m0s
[18:32:43.480] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:64:0)
[18:32:43.480] Timer tick, asking Buildbucket for the build status
[18:32:43.779] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:43.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:65:0) after 1m0s
[18:33:43.815] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:65:0)
[18:33:43.815] Timer tick, asking Buildbucket for the build status
[18:33:43.885] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:43.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:66:0) after 1m0s
[18:34:43.951] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:66:0)
[18:34:43.951] Timer tick, asking Buildbucket for the build status
[18:34:44.457] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:44.457] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:67:0) after 1m0s
[18:35:44.495] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:67:0)
[18:35:44.495] Timer tick, asking Buildbucket for the build status
[18:35:44.686] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:44.686] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:68:0) after 1m0s
[18:36:44.691] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:68:0)
[18:36:44.691] Timer tick, asking Buildbucket for the build status
[18:36:44.780] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:44.780] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:69:0) after 1m0s
[18:37:44.793] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:69:0)
[18:37:44.793] Timer tick, asking Buildbucket for the build status
[18:37:45.037] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:45.037] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:70:0) after 1m0s
[18:38:45.197] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:70:0)
[18:38:45.197] Timer tick, asking Buildbucket for the build status
[18:38:45.277] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:45.277] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:71:0) after 1m0s
[18:39:45.356] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:71:0)
[18:39:45.356] Timer tick, asking Buildbucket for the build status
[18:39:45.419] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:45.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:72:0) after 1m0s
[18:40:45.600] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:72:0)
[18:40:45.600] Timer tick, asking Buildbucket for the build status
[18:40:45.724] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:45.724] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:73:0) after 1m0s
[18:41:45.808] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:73:0)
[18:41:45.808] Timer tick, asking Buildbucket for the build status
[18:41:46.089] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:46.089] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:74:0) after 1m0s
[18:42:46.193] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:74:0)
[18:42:46.193] Timer tick, asking Buildbucket for the build status
[18:42:46.269] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:46.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:75:0) after 1m0s
[18:43:46.331] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:75:0)
[18:43:46.331] Timer tick, asking Buildbucket for the build status
[18:43:46.417] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:46.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:76:0) after 1m0s
[18:44:46.433] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:76:0)
[18:44:46.433] Timer tick, asking Buildbucket for the build status
[18:44:46.516] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:46.516] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:77:0) after 1m0s
[18:45:46.612] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:77:0)
[18:45:46.612] Timer tick, asking Buildbucket for the build status
[18:45:46.983] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:46.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:78:0) after 1m0s
[18:46:47.018] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:78:0)
[18:46:47.018] Timer tick, asking Buildbucket for the build status
[18:46:47.312] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:47.312] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:79:0) after 1m0s
[18:47:47.337] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:79:0)
[18:47:47.337] Timer tick, asking Buildbucket for the build status
[18:47:47.422] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:47.422] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:80:0) after 1m0s
[18:48:47.461] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:80:0)
[18:48:47.461] Timer tick, asking Buildbucket for the build status
[18:48:47.583] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:47.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:81:0) after 1m0s
[18:49:47.638] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:81:0)
[18:49:47.638] Timer tick, asking Buildbucket for the build status
[18:49:47.776] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:47.776] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:82:0) after 1m0s
[18:50:47.875] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:82:0)
[18:50:47.875] Timer tick, asking Buildbucket for the build status
[18:50:48.062] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:48.062] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:83:0) after 1m0s
[18:51:48.134] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:83:0)
[18:51:48.134] Timer tick, asking Buildbucket for the build status
[18:51:48.496] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:48.496] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:84:0) after 1m0s
[18:52:48.562] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:84:0)
[18:52:48.562] Timer tick, asking Buildbucket for the build status
[18:52:48.637] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:48.637] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:85:0) after 1m0s
[18:53:48.697] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:85:0)
[18:53:48.697] Timer tick, asking Buildbucket for the build status
[18:53:48.817] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:48.817] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:86:0) after 1m0s
[18:54:48.836] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:86:0)
[18:54:48.836] Timer tick, asking Buildbucket for the build status
[18:54:49.538] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:49.538] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:87:0) after 1m0s
[18:55:49.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:87:0)
[18:55:49.528] Timer tick, asking Buildbucket for the build status
[18:55:50.119] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:88:0) after 1m0s
[18:56:50.158] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:88:0)
[18:56:50.158] Timer tick, asking Buildbucket for the build status
[18:56:50.275] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:50.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:89:0) after 1m0s
[18:57:50.353] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:89:0)
[18:57:50.353] Timer tick, asking Buildbucket for the build status
[18:57:50.574] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:50.574] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:90:0) after 1m0s
[18:58:50.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:90:0)
[18:58:50.681] Timer tick, asking Buildbucket for the build status
[18:58:51.184] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:51.184] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:91:0) after 1m0s
[18:59:51.209] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:91:0)
[18:59:51.209] Timer tick, asking Buildbucket for the build status
[18:59:51.445] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:51.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:92:0) after 1m0s
[19:00:51.432] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:92:0)
[19:00:51.432] Timer tick, asking Buildbucket for the build status
[19:00:53.265] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:53.265] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:93:0) after 1m0s
[19:01:53.295] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:93:0)
[19:01:53.295] Timer tick, asking Buildbucket for the build status
[19:01:53.549] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:53.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:94:0) after 1m0s
[19:02:53.573] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:94:0)
[19:02:53.573] Timer tick, asking Buildbucket for the build status
[19:02:53.665] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:53.665] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:95:0) after 1m0s
[19:03:53.693] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:95:0)
[19:03:53.694] Timer tick, asking Buildbucket for the build status
[19:03:53.840] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:53.840] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:96:0) after 1m0s
[19:04:53.820] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:96:0)
[19:04:53.820] Timer tick, asking Buildbucket for the build status
[19:04:54.604] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:54.604] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:97:0) after 1m0s
[19:05:54.649] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:97:0)
[19:05:54.649] Timer tick, asking Buildbucket for the build status
[19:05:54.855] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:98:0) after 1m0s
[19:06:54.896] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:98:0)
[19:06:54.897] Timer tick, asking Buildbucket for the build status
[19:06:55.015] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:55.015] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:99:0) after 1m0s
[19:07:55.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:99:0)
[19:07:55.017] Timer tick, asking Buildbucket for the build status
[19:07:55.455] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:55.455] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:100:0) after 1m0s
[19:08:55.508] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:100:0)
[19:08:55.508] Timer tick, asking Buildbucket for the build status
[19:08:55.660] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:55.660] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:101:0) after 1m0s
[19:09:55.679] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:101:0)
[19:09:55.679] Timer tick, asking Buildbucket for the build status
[19:09:55.747] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:55.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:102:0) after 1m0s
[19:10:55.779] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:102:0)
[19:10:55.779] Timer tick, asking Buildbucket for the build status
[19:10:56.049] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:56.049] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:103:0) after 1m0s
[19:11:56.054] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:103:0)
[19:11:56.054] Timer tick, asking Buildbucket for the build status
[19:11:56.232] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:56.232] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:104:0) after 1m0s
[19:12:56.323] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:104:0)
[19:12:56.323] Timer tick, asking Buildbucket for the build status
[19:12:56.445] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:56.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:105:0) after 1m0s
[19:13:56.488] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:105:0)
[19:13:56.488] Timer tick, asking Buildbucket for the build status
[19:13:56.726] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:56.726] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:106:0) after 1m0s
[19:14:56.842] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:106:0)
[19:14:56.842] Timer tick, asking Buildbucket for the build status
[19:14:57.230] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:57.230] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:107:0) after 1m0s
[19:15:57.251] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:107:0)
[19:15:57.251] Timer tick, asking Buildbucket for the build status
[19:15:57.440] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:57.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:108:0) after 1m0s
[19:16:57.463] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:108:0)
[19:16:57.463] Timer tick, asking Buildbucket for the build status
[19:16:58.209] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:58.209] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:109:0) after 1m0s
[19:17:58.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:109:0)
[19:17:58.233] Timer tick, asking Buildbucket for the build status
[19:17:58.472] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:110:0) after 1m0s
[19:18:58.601] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:110:0)
[19:18:58.601] Timer tick, asking Buildbucket for the build status
[19:18:58.860] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:58.860] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:111:0) after 1m0s
[19:19:58.961] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:111:0)
[19:19:58.961] Timer tick, asking Buildbucket for the build status
[19:19:59.055] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:59.055] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:112:0) after 1m0s
[19:20:59.155] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:112:0)
[19:20:59.155] Timer tick, asking Buildbucket for the build status
[19:20:59.440] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:59.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:113:0) after 1m0s
[19:21:59.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:113:0)
[19:21:59.460] Timer tick, asking Buildbucket for the build status
[19:22:00.186] Build 8857747193736995408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:00.186] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023177255720783840:114:0) after 1m0s
[19:22:08.790] Received PubSub notification, asking Buildbucket for the build status
[19:22:08.882] Build 8857747193736995408: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:22:08.882] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610997728334544",
"created_by": "project:chromium",
"created_ts": "1610991022321206",
"failure_reason": "BUILD_FAILURE",
"id": "8857747193736995408",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2da48363c639636ea8e4f77a5e5e33c51475205c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed.\"}, \"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\": \"2da48363c639636ea8e4f77a5e5e33c51475205c\", \"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-106-efhb\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7329, \"got_angle_revision\": \"68f15c734d5cef3bfa3f8d86654fd7a5808679ec\", \"got_dawn_revision\": \"5c20f1ec9ca95008eb452e04133c332af3a2b3fa\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"2da48363c639636ea8e4f77a5e5e33c51475205c\", \"got_revision_cp\": \"refs/heads/master@{#844562}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"765e4f6f0ef4a47026202c7991312d412b99b524\", \"got_v8_revision\": \"e02dfe6cf35d7b3724c84b162c628b2c960bd9c7\", \"got_v8_revision_cp\": \"refs/heads/9.0.5@{#1}\", \"got_webrtc_revision\": \"12971a22d265c7ba1c3c388f5d487580939a1162\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33014}\", \"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\": \"2da48363c639636ea8e4f77a5e5e33c51475205c\", \"swarm_hashes_refs/heads/master(at){#844562}_without_patch\": {\"android_browsertests\": \"f8ee724a6c2732addf3b234c42f74bea2f4be2d1\", \"chrome_public_test_apk\": \"0aa1ea843f084b2c1cdbc7b1bfe0939b8e6a6d53\", \"content_browsertests\": \"5c9b3c763711b2fbd9390d34b4eb1c9c1d6d3f7e\", \"content_shell_test_apk\": \"ef1225d4d87348ae0f86d69d1cd35a2e60563019\", \"content_unittests\": \"3098af2f142236fd5b1b85e250a65798da0dbfb8\", \"unit_tests\": \"1c24bc9b126e8adb4cce0d82dafe36f7706f963e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_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-106-efhb\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610991044210206",
"status": "COMPLETED",
"status_changed_ts": "1610991044638784",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7329",
"builder:android-bfcache-rel",
"buildset:commit/git/2da48363c639636ea8e4f77a5e5e33c51475205c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da48363c639636ea8e4f77a5e5e33c51475205c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023177255720783840",
"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/8857747193736995408/+/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:512f5dbbaeecca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610997728457470",
"url": "https://ci.chromium.org/b/8857747193736995408",
"utcnow_ts": "1610997728866735"
}
}
[19:22:08.883] Invocation finished in 1h51m47.836207863s with status FAILED