[20:18:14.852] New invocation is queued and will start shortly
[20:18:15.947] Starting the invocation (attempt 1)
[20:18:15.982] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:18:15.982] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:18:15.983] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018546247298611376",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9018546247298611376",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"gitiles_ref:refs/heads/master"
]
}
[20:18:17.560] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615407496132663",
"id": "8853116185294493184",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615407497217851",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/8207",
"builder:android-bfcache-rel",
"buildset:commit/git/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018546247298611376",
"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/8853116185294493184/+/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": "1615407497224188",
"url": "https://ci.chromium.org/b/8853116185294493184",
"utcnow_ts": "1615407497547801"
}
}
[20:18:17.560] Task URL: https://ci.chromium.org/b/8853116185294493184
[20:18:17.560] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:2:0) after 1m0s
[20:18:45.496] Received PubSub notification, asking Buildbucket for the build status
[20:18:45.589] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:17.562] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:2:0)
[20:19:17.562] Timer tick, asking Buildbucket for the build status
[20:19:17.632] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:17.632] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:4:0) after 1m0s
[20:20:17.649] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:4:0)
[20:20:17.649] Timer tick, asking Buildbucket for the build status
[20:20:17.707] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:17.707] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:5:0) after 1m0s
[20:21:17.720] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:5:0)
[20:21:17.720] Timer tick, asking Buildbucket for the build status
[20:21:17.940] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:17.940] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:6:0) after 1m0s
[20:22:17.956] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:6:0)
[20:22:17.956] Timer tick, asking Buildbucket for the build status
[20:22:18.042] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:18.042] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:7:0) after 1m0s
[20:23:18.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:7:0)
[20:23:18.059] Timer tick, asking Buildbucket for the build status
[20:23:18.209] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:18.209] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:8:0) after 1m0s
[20:24:18.226] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:8:0)
[20:24:18.226] Timer tick, asking Buildbucket for the build status
[20:24:18.317] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:18.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:9:0) after 1m0s
[20:25:18.334] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:9:0)
[20:25:18.334] Timer tick, asking Buildbucket for the build status
[20:25:18.516] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:18.516] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:10:0) after 1m0s
[20:26:18.581] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:10:0)
[20:26:18.581] Timer tick, asking Buildbucket for the build status
[20:26:18.643] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:18.643] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:11:0) after 1m0s
[20:27:18.725] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:11:0)
[20:27:18.725] Timer tick, asking Buildbucket for the build status
[20:27:18.818] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:18.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:12:0) after 1m0s
[20:28:18.940] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:12:0)
[20:28:18.940] Timer tick, asking Buildbucket for the build status
[20:28:19.252] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:19.252] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:13:0) after 1m0s
[20:29:19.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:13:0)
[20:29:19.286] Timer tick, asking Buildbucket for the build status
[20:29:19.353] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:19.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:14:0) after 1m0s
[20:30:19.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:14:0)
[20:30:19.480] Timer tick, asking Buildbucket for the build status
[20:30:19.654] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:19.654] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:15:0) after 1m0s
[20:31:19.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:15:0)
[20:31:19.784] Timer tick, asking Buildbucket for the build status
[20:31:19.919] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:19.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:16:0) after 1m0s
[20:32:20.004] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:16:0)
[20:32:20.004] Timer tick, asking Buildbucket for the build status
[20:32:20.072] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:17:0) after 1m0s
[20:33:20.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:17:0)
[20:33:20.087] Timer tick, asking Buildbucket for the build status
[20:33:20.255] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:20.255] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:18:0) after 1m0s
[20:34:20.276] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:18:0)
[20:34:20.276] Timer tick, asking Buildbucket for the build status
[20:34:20.368] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:20.368] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:19:0) after 1m0s
[20:35:20.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:19:0)
[20:35:20.385] Timer tick, asking Buildbucket for the build status
[20:35:20.463] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:20.463] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:20:0) after 1m0s
[20:36:20.478] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:20:0)
[20:36:20.478] Timer tick, asking Buildbucket for the build status
[20:36:20.641] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:20.641] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:21:0) after 1m0s
[20:37:20.910] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:21:0)
[20:37:20.910] Timer tick, asking Buildbucket for the build status
[20:37:21.230] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:21.230] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:22:0) after 1m0s
[20:38:21.288] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:22:0)
[20:38:21.288] Timer tick, asking Buildbucket for the build status
[20:38:21.371] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:21.371] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:23:0) after 1m0s
[20:39:21.515] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:23:0)
[20:39:21.515] Timer tick, asking Buildbucket for the build status
[20:39:21.679] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:21.679] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:24:0) after 1m0s
[20:40:21.692] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:24:0)
[20:40:21.692] Timer tick, asking Buildbucket for the build status
[20:40:21.748] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:21.748] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:25:0) after 1m0s
[20:41:21.862] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:25:0)
[20:41:21.862] Timer tick, asking Buildbucket for the build status
[20:41:21.941] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:21.941] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:26:0) after 1m0s
[20:42:22.058] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:26:0)
[20:42:22.058] Timer tick, asking Buildbucket for the build status
[20:42:22.156] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:22.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:27:0) after 1m0s
[20:43:22.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:27:0)
[20:43:22.312] Timer tick, asking Buildbucket for the build status
[20:43:22.404] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:28:0) after 1m0s
[20:44:25.016] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:28:0)
[20:44:25.016] Timer tick, asking Buildbucket for the build status
[20:44:25.101] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:25.101] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:29:0) after 1m0s
[20:45:25.275] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:29:0)
[20:45:25.275] Timer tick, asking Buildbucket for the build status
[20:45:25.341] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:25.341] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:30:0) after 1m0s
[20:46:25.547] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:30:0)
[20:46:25.547] Timer tick, asking Buildbucket for the build status
[20:46:31.060] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:31.060] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:31:0) after 1m0s
[20:47:31.252] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:31:0)
[20:47:31.252] Timer tick, asking Buildbucket for the build status
[20:47:31.352] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:31.352] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:32:0) after 1m0s
[20:48:31.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:32:0)
[20:48:31.567] Timer tick, asking Buildbucket for the build status
[20:48:31.677] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:31.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:33:0) after 1m0s
[20:49:31.821] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:33:0)
[20:49:31.821] Timer tick, asking Buildbucket for the build status
[20:49:31.984] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:31.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:34:0) after 1m0s
[20:50:32.220] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:34:0)
[20:50:32.220] Timer tick, asking Buildbucket for the build status
[20:50:32.415] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:32.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:35:0) after 1m0s
[20:51:33.382] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:35:0)
[20:51:33.382] Timer tick, asking Buildbucket for the build status
[20:51:33.543] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:33.543] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:36:0) after 1m0s
[20:52:33.662] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:36:0)
[20:52:33.662] Timer tick, asking Buildbucket for the build status
[20:52:33.750] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:33.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:37:0) after 1m0s
[20:53:33.824] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:37:0)
[20:53:33.824] Timer tick, asking Buildbucket for the build status
[20:53:33.965] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:33.965] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:38:0) after 1m0s
[20:54:34.127] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:38:0)
[20:54:34.127] Timer tick, asking Buildbucket for the build status
[20:54:34.196] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:34.196] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:39:0) after 1m0s
[20:55:34.409] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:39:0)
[20:55:34.409] Timer tick, asking Buildbucket for the build status
[20:55:34.482] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:34.482] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:40:0) after 1m0s
[20:56:34.738] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:40:0)
[20:56:34.738] Timer tick, asking Buildbucket for the build status
[20:56:35.021] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:35.021] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:41:0) after 1m0s
[20:57:35.094] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:41:0)
[20:57:35.094] Timer tick, asking Buildbucket for the build status
[20:57:35.197] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:35.197] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:42:0) after 1m0s
[20:58:35.297] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:42:0)
[20:58:35.297] Timer tick, asking Buildbucket for the build status
[20:58:35.458] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:35.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:43:0) after 1m0s
[20:59:35.462] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:43:0)
[20:59:35.462] Timer tick, asking Buildbucket for the build status
[20:59:35.534] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:35.534] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:44:0) after 1m0s
[21:00:35.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:44:0)
[21:00:35.603] Timer tick, asking Buildbucket for the build status
[21:00:35.778] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:35.778] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:45:0) after 1m0s
[21:01:35.817] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:45:0)
[21:01:35.817] Timer tick, asking Buildbucket for the build status
[21:01:35.945] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.945] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:46:0) after 1m0s
[21:02:36.003] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:46:0)
[21:02:36.003] Timer tick, asking Buildbucket for the build status
[21:02:36.207] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:47:0) after 1m0s
[21:03:36.226] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:47:0)
[21:03:36.226] Timer tick, asking Buildbucket for the build status
[21:03:36.424] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:36.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:48:0) after 1m0s
[21:04:36.466] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:48:0)
[21:04:36.466] Timer tick, asking Buildbucket for the build status
[21:04:36.530] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.530] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:49:0) after 1m0s
[21:05:36.580] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:49:0)
[21:05:36.580] Timer tick, asking Buildbucket for the build status
[21:05:36.663] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.663] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:50:0) after 1m0s
[21:06:36.686] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:50:0)
[21:06:36.686] Timer tick, asking Buildbucket for the build status
[21:06:36.763] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:36.763] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:51:0) after 1m0s
[21:07:36.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:51:0)
[21:07:36.800] Timer tick, asking Buildbucket for the build status
[21:07:36.911] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:36.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:52:0) after 1m0s
[21:08:36.928] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:52:0)
[21:08:36.928] Timer tick, asking Buildbucket for the build status
[21:08:37.112] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:37.112] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:53:0) after 1m0s
[21:09:37.130] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:53:0)
[21:09:37.130] Timer tick, asking Buildbucket for the build status
[21:09:37.325] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:37.325] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:54:0) after 1m0s
[21:10:37.326] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:54:0)
[21:10:37.326] Timer tick, asking Buildbucket for the build status
[21:10:37.448] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:37.448] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:55:0) after 1m0s
[21:11:37.473] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:55:0)
[21:11:37.473] Timer tick, asking Buildbucket for the build status
[21:11:37.575] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:37.575] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:56:0) after 1m0s
[21:12:37.619] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:56:0)
[21:12:37.619] Timer tick, asking Buildbucket for the build status
[21:12:37.907] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:37.907] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:57:0) after 1m0s
[21:13:37.938] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:57:0)
[21:13:37.938] Timer tick, asking Buildbucket for the build status
[21:13:38.020] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:38.020] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:58:0) after 1m0s
[21:14:38.106] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:58:0)
[21:14:38.106] Timer tick, asking Buildbucket for the build status
[21:14:38.452] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:38.452] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:59:0) after 1m0s
[21:15:38.446] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:59:0)
[21:15:38.446] Timer tick, asking Buildbucket for the build status
[21:15:38.524] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:60:0) after 1m0s
[21:16:38.532] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:60:0)
[21:16:38.532] Timer tick, asking Buildbucket for the build status
[21:16:38.665] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:38.665] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:61:0) after 1m0s
[21:17:38.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:61:0)
[21:17:38.716] Timer tick, asking Buildbucket for the build status
[21:17:38.830] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:38.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:62:0) after 1m0s
[21:18:38.848] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:62:0)
[21:18:38.848] Timer tick, asking Buildbucket for the build status
[21:18:38.934] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:38.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:63:0) after 1m0s
[21:19:39.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:63:0)
[21:19:39.039] Timer tick, asking Buildbucket for the build status
[21:19:39.146] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:64:0) after 1m0s
[21:20:39.251] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:64:0)
[21:20:39.251] Timer tick, asking Buildbucket for the build status
[21:20:39.319] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:39.319] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:65:0) after 1m0s
[21:21:39.436] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:65:0)
[21:21:39.436] Timer tick, asking Buildbucket for the build status
[21:21:39.519] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:39.519] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:66:0) after 1m0s
[21:22:39.596] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:66:0)
[21:22:39.596] Timer tick, asking Buildbucket for the build status
[21:22:39.676] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:39.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:67:0) after 1m0s
[21:23:39.671] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:67:0)
[21:23:39.671] Timer tick, asking Buildbucket for the build status
[21:23:39.766] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:39.766] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:68:0) after 1m0s
[21:24:39.756] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:68:0)
[21:24:39.756] Timer tick, asking Buildbucket for the build status
[21:24:39.834] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:39.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:69:0) after 1m0s
[21:25:39.856] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:69:0)
[21:25:39.856] Timer tick, asking Buildbucket for the build status
[21:25:39.948] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:39.948] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:70:0) after 1m0s
[21:26:40.001] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:70:0)
[21:26:40.001] Timer tick, asking Buildbucket for the build status
[21:26:40.080] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:40.080] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:71:0) after 1m0s
[21:27:40.202] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:71:0)
[21:27:40.202] Timer tick, asking Buildbucket for the build status
[21:27:40.287] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.287] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:72:0) after 1m0s
[21:28:40.416] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:72:0)
[21:28:40.416] Timer tick, asking Buildbucket for the build status
[21:28:40.490] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:40.490] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:73:0) after 1m0s
[21:29:40.551] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:73:0)
[21:29:40.551] Timer tick, asking Buildbucket for the build status
[21:29:40.617] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:40.617] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:74:0) after 1m0s
[21:30:40.714] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:74:0)
[21:30:40.714] Timer tick, asking Buildbucket for the build status
[21:30:40.794] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:40.794] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:75:0) after 1m0s
[21:31:40.887] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:75:0)
[21:31:40.887] Timer tick, asking Buildbucket for the build status
[21:31:40.963] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:40.963] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:76:0) after 1m0s
[21:32:40.987] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:76:0)
[21:32:40.987] Timer tick, asking Buildbucket for the build status
[21:32:41.054] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:41.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:77:0) after 1m0s
[21:33:41.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:77:0)
[21:33:41.034] Timer tick, asking Buildbucket for the build status
[21:33:41.115] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:41.115] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:78:0) after 1m0s
[21:34:41.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:78:0)
[21:34:41.303] Timer tick, asking Buildbucket for the build status
[21:34:41.413] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:41.413] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:79:0) after 1m0s
[21:35:41.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:79:0)
[21:35:41.465] Timer tick, asking Buildbucket for the build status
[21:35:41.532] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:41.532] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:80:0) after 1m0s
[21:36:41.703] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:80:0)
[21:36:41.703] Timer tick, asking Buildbucket for the build status
[21:36:41.818] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:41.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:81:0) after 1m0s
[21:37:42.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:81:0)
[21:37:42.039] Timer tick, asking Buildbucket for the build status
[21:37:42.159] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:42.159] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:82:0) after 1m0s
[21:38:42.213] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:82:0)
[21:38:42.213] Timer tick, asking Buildbucket for the build status
[21:38:42.295] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:42.295] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:83:0) after 1m0s
[21:39:42.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:83:0)
[21:39:42.306] Timer tick, asking Buildbucket for the build status
[21:39:42.396] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:42.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:84:0) after 1m0s
[21:40:42.506] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:84:0)
[21:40:42.506] Timer tick, asking Buildbucket for the build status
[21:40:42.922] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:42.922] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:85:0) after 1m0s
[21:41:42.937] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:85:0)
[21:41:42.937] Timer tick, asking Buildbucket for the build status
[21:41:43.244] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:43.244] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:86:0) after 1m0s
[21:42:43.337] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:86:0)
[21:42:43.337] Timer tick, asking Buildbucket for the build status
[21:42:43.511] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:43.511] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:87:0) after 1m0s
[21:43:43.621] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:87:0)
[21:43:43.621] Timer tick, asking Buildbucket for the build status
[21:43:43.739] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:43.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:88:0) after 1m0s
[21:44:43.763] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:88:0)
[21:44:43.763] Timer tick, asking Buildbucket for the build status
[21:44:43.836] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:43.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:89:0) after 1m0s
[21:45:43.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:89:0)
[21:45:43.859] Timer tick, asking Buildbucket for the build status
[21:45:44.102] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:44.102] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:90:0) after 1m0s
[21:46:44.162] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:90:0)
[21:46:44.162] Timer tick, asking Buildbucket for the build status
[21:46:44.439] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:44.439] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:91:0) after 1m0s
[21:47:44.481] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:91:0)
[21:47:44.481] Timer tick, asking Buildbucket for the build status
[21:47:44.627] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:44.627] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:92:0) after 1m0s
[21:48:44.671] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:92:0)
[21:48:44.671] Timer tick, asking Buildbucket for the build status
[21:48:44.762] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:44.762] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:93:0) after 1m0s
[21:49:44.785] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:93:0)
[21:49:44.785] Timer tick, asking Buildbucket for the build status
[21:49:44.865] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:44.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:94:0) after 1m0s
[21:50:44.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:94:0)
[21:50:44.915] Timer tick, asking Buildbucket for the build status
[21:50:45.132] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:95:0) after 1m0s
[21:51:45.180] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:95:0)
[21:51:45.180] Timer tick, asking Buildbucket for the build status
[21:51:45.239] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:45.239] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:96:0) after 1m0s
[21:52:45.232] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:96:0)
[21:52:45.232] Timer tick, asking Buildbucket for the build status
[21:52:45.302] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:45.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:97:0) after 1m0s
[21:53:45.357] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:97:0)
[21:53:45.357] Timer tick, asking Buildbucket for the build status
[21:53:45.450] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:45.450] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:98:0) after 1m0s
[21:54:45.503] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:98:0)
[21:54:45.503] Timer tick, asking Buildbucket for the build status
[21:54:45.659] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:45.659] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:99:0) after 1m0s
[21:55:45.698] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:99:0)
[21:55:45.698] Timer tick, asking Buildbucket for the build status
[21:55:45.781] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:45.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:100:0) after 1m0s
[21:56:45.788] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:100:0)
[21:56:45.788] Timer tick, asking Buildbucket for the build status
[21:56:45.856] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:45.856] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:101:0) after 1m0s
[21:57:45.926] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:101:0)
[21:57:45.926] Timer tick, asking Buildbucket for the build status
[21:57:45.999] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:45.999] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:102:0) after 1m0s
[21:58:46.002] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:102:0)
[21:58:46.002] Timer tick, asking Buildbucket for the build status
[21:58:46.295] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:46.295] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:103:0) after 1m0s
[21:59:46.361] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:103:0)
[21:59:46.361] Timer tick, asking Buildbucket for the build status
[21:59:46.437] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:46.437] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:104:0) after 1m0s
[22:00:46.512] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:104:0)
[22:00:46.512] Timer tick, asking Buildbucket for the build status
[22:00:46.606] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:46.606] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:105:0) after 1m0s
[22:01:46.719] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:105:0)
[22:01:46.719] Timer tick, asking Buildbucket for the build status
[22:01:46.850] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:46.850] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:106:0) after 1m0s
[22:02:46.958] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:106:0)
[22:02:46.958] Timer tick, asking Buildbucket for the build status
[22:02:47.072] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:47.072] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:107:0) after 1m0s
[22:03:47.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:107:0)
[22:03:47.129] Timer tick, asking Buildbucket for the build status
[22:03:47.224] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:47.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:108:0) after 1m0s
[22:04:47.216] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:108:0)
[22:04:47.216] Timer tick, asking Buildbucket for the build status
[22:04:47.302] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:47.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:109:0) after 1m0s
[22:05:47.324] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:109:0)
[22:05:47.324] Timer tick, asking Buildbucket for the build status
[22:05:47.396] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:47.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:110:0) after 1m0s
[22:06:47.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:110:0)
[22:06:47.428] Timer tick, asking Buildbucket for the build status
[22:06:47.550] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:47.550] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:111:0) after 1m0s
[22:07:47.569] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:111:0)
[22:07:47.569] Timer tick, asking Buildbucket for the build status
[22:07:47.758] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:47.758] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:112:0) after 1m0s
[22:08:47.729] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:112:0)
[22:08:47.729] Timer tick, asking Buildbucket for the build status
[22:08:48.116] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.116] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:113:0) after 1m0s
[22:09:48.166] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:113:0)
[22:09:48.166] Timer tick, asking Buildbucket for the build status
[22:09:48.417] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:48.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:114:0) after 1m0s
[22:10:48.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:114:0)
[22:10:48.437] Timer tick, asking Buildbucket for the build status
[22:10:48.528] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:48.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:115:0) after 1m0s
[22:11:48.549] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:115:0)
[22:11:48.549] Timer tick, asking Buildbucket for the build status
[22:11:48.623] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:48.623] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:116:0) after 1m0s
[22:12:48.664] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:116:0)
[22:12:48.664] Timer tick, asking Buildbucket for the build status
[22:12:48.737] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:48.737] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:117:0) after 1m0s
[22:13:48.752] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:117:0)
[22:13:48.752] Timer tick, asking Buildbucket for the build status
[22:13:48.894] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:48.894] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:118:0) after 1m0s
[22:14:48.930] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:118:0)
[22:14:48.930] Timer tick, asking Buildbucket for the build status
[22:14:49.004] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:49.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:119:0) after 1m0s
[22:15:48.982] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:119:0)
[22:15:48.983] Timer tick, asking Buildbucket for the build status
[22:15:49.052] Build 8853116185294493184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:49.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9018546247298611376:120:0) after 1m0s
[22:16:19.296] Received PubSub notification, asking Buildbucket for the build status
[22:16:19.393] Build 8853116185294493184: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:16:19.393] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615414578905054",
"created_by": "project:chromium",
"created_ts": "1615407496132663",
"failure_reason": "BUILD_FAILURE",
"id": "8853116185294493184",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 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.payments.AndroidPaymentAppFinderUnitTest#testQueryUnsupportedPaymentMethod\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryNoPaymentMethod\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryWithoutLabel\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryBasicCardsWithTwoApps\\n\\n- ...5 more failure(s) (9 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/PrerenderBrowserTest.PrerenderedPageCrossSiteNavigation/WebContents\"}, \"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\": \"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\", \"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-139-nnca\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 8207, \"got_angle_revision\": \"b2f547f4f423f492c4483d901d1b3ad5f3ad8798\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\", \"got_revision_cp\": \"refs/heads/master@{#861641}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"023914a27b634063766fee96f2e6763513f5b059\", \"got_v8_revision\": \"de5df026428a7e343abb139064e99eac24cd2404\", \"got_v8_revision_cp\": \"refs/heads/9.1.90@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d\", \"swarm_hashes_refs/heads/master(at){#861641}_without_patch\": {\"android_browsertests\": \"22be67a4bb384acc0262301348e2be0dd53b727f\", \"chrome_public_test_apk\": \"0945ae64c890dcf1cb1562826fce24b87910193a\", \"content_browsertests\": \"824ba236385a8823802565e96a1ce2d54152a7a0\", \"content_shell_test_apk\": \"57c19353ebc089efb4ceb67152c836c699fe0743\", \"content_unittests\": \"a91effc09c7553bd4e0931dcc36b3ebefabefb9a\", \"unit_tests\": \"079b45eb251c310405a899692f39d1c53032949f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8424eb90fa14b61ae49b4a2af2404de06c5c2a9a9345d31da54fda5995bf0e92_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-139-nnca\"], \"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\": [\"5746-91ff3bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 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.payments.AndroidPaymentAppFinderUnitTest#testQueryUnsupportedPaymentMethod\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryNoPaymentMethod\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryWithoutLabel\\n\\n- org.chromium.chrome.browser.payments.AndroidPaymentAppFinderUnitTest#testQueryBasicCardsWithTwoApps\\n\\n- ...5 more failure(s) (9 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- All/PrerenderBrowserTest.PrerenderedPageCrossSiteNavigation/WebContents\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615407524855210",
"status": "COMPLETED",
"status_changed_ts": "1615407525353216",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/8207",
"builder:android-bfcache-rel",
"buildset:commit/git/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fee3f1ba3a60f12642c2a89ae6d82d49e4a7f7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018546247298611376",
"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/8853116185294493184/+/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:52369bc9c3668a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615414578905564",
"url": "https://ci.chromium.org/b/8853116185294493184",
"utcnow_ts": "1615414579385341"
}
}
[22:16:19.393] Invocation finished in 1h58m4.556302138s with status FAILED