[06:24:08.710] New invocation is queued and will start shortly
[06:24:09.786] Starting the invocation (attempt 1)
[06:24:09.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:24:09.821] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:24:09.821] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023672154599769280",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9023672154599769280",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"gitiles_ref:refs/heads/master"
]
}
[06:24:10.448] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610519049913378",
"id": "8858242092676473360",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610519050345194",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7242",
"builder:android-bfcache-rel",
"buildset:commit/git/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023672154599769280",
"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/8858242092676473360/+/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": "1610519050345310",
"url": "https://ci.chromium.org/b/8858242092676473360",
"utcnow_ts": "1610519050440702"
}
}
[06:24:10.448] Task URL: https://ci.chromium.org/b/8858242092676473360
[06:24:10.448] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:2:0) after 1m0s
[06:25:10.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:2:0)
[06:25:10.567] Timer tick, asking Buildbucket for the build status
[06:25:10.682] Build 8858242092676473360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:10.682] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:3:0) after 1m0s
[06:26:00.451] Received PubSub notification, asking Buildbucket for the build status
[06:26:00.542] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:10.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:3:0)
[06:26:10.681] Timer tick, asking Buildbucket for the build status
[06:26:10.751] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:10.751] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:5:0) after 1m0s
[06:27:10.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:5:0)
[06:27:10.757] Timer tick, asking Buildbucket for the build status
[06:27:10.848] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:10.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:6:0) after 1m0s
[06:28:10.850] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:6:0)
[06:28:10.850] Timer tick, asking Buildbucket for the build status
[06:28:10.894] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:7:0) after 1m0s
[06:29:10.894] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:7:0)
[06:29:10.894] Timer tick, asking Buildbucket for the build status
[06:29:11.261] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:11.261] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:8:0) after 1m0s
[06:30:11.314] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:8:0)
[06:30:11.314] Timer tick, asking Buildbucket for the build status
[06:30:11.463] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:11.463] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:9:0) after 1m0s
[06:31:11.496] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:9:0)
[06:31:11.496] Timer tick, asking Buildbucket for the build status
[06:31:11.602] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:11.602] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:10:0) after 1m0s
[06:32:11.619] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:10:0)
[06:32:11.619] Timer tick, asking Buildbucket for the build status
[06:32:11.744] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:11.744] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:11:0) after 1m0s
[06:33:11.788] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:11:0)
[06:33:11.788] Timer tick, asking Buildbucket for the build status
[06:33:12.581] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:12.581] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:12:0) after 1m0s
[06:34:12.556] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:12:0)
[06:34:12.556] Timer tick, asking Buildbucket for the build status
[06:34:12.906] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:12.906] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:13:0) after 1m0s
[06:35:12.945] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:13:0)
[06:35:12.945] Timer tick, asking Buildbucket for the build status
[06:35:13.122] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:13.122] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:14:0) after 1m0s
[06:36:13.194] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:14:0)
[06:36:13.194] Timer tick, asking Buildbucket for the build status
[06:36:13.280] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:13.280] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:15:0) after 1m0s
[06:37:13.316] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:15:0)
[06:37:13.316] Timer tick, asking Buildbucket for the build status
[06:37:13.419] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:13.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:16:0) after 1m0s
[06:38:13.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:16:0)
[06:38:13.528] Timer tick, asking Buildbucket for the build status
[06:38:13.611] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:13.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:17:0) after 1m0s
[06:39:13.682] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:17:0)
[06:39:13.682] Timer tick, asking Buildbucket for the build status
[06:39:13.822] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:13.822] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:18:0) after 1m0s
[06:40:13.951] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:18:0)
[06:40:13.951] Timer tick, asking Buildbucket for the build status
[06:40:14.026] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:14.026] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:19:0) after 1m0s
[06:41:14.095] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:19:0)
[06:41:14.095] Timer tick, asking Buildbucket for the build status
[06:41:14.202] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:14.202] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:20:0) after 1m0s
[06:42:14.227] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:20:0)
[06:42:14.227] Timer tick, asking Buildbucket for the build status
[06:42:14.362] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:14.362] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:21:0) after 1m0s
[06:43:14.384] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:21:0)
[06:43:14.384] Timer tick, asking Buildbucket for the build status
[06:43:14.460] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:14.460] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:22:0) after 1m0s
[06:44:14.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:22:0)
[06:44:14.479] Timer tick, asking Buildbucket for the build status
[06:44:14.655] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:14.655] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:23:0) after 1m0s
[06:45:14.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:23:0)
[06:45:14.681] Timer tick, asking Buildbucket for the build status
[06:45:14.991] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:14.991] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:24:0) after 1m0s
[06:46:15.070] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:24:0)
[06:46:15.070] Timer tick, asking Buildbucket for the build status
[06:46:15.154] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:15.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:25:0) after 1m0s
[06:47:15.255] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:25:0)
[06:47:15.255] Timer tick, asking Buildbucket for the build status
[06:47:15.405] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:15.405] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:26:0) after 1m0s
[06:48:15.423] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:26:0)
[06:48:15.423] Timer tick, asking Buildbucket for the build status
[06:48:15.494] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:15.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:27:0) after 1m0s
[06:49:15.517] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:27:0)
[06:49:15.517] Timer tick, asking Buildbucket for the build status
[06:49:16.498] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:16.498] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:28:0) after 1m0s
[06:50:16.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:28:0)
[06:50:16.527] Timer tick, asking Buildbucket for the build status
[06:50:16.595] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:16.595] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:29:0) after 1m0s
[06:51:16.669] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:29:0)
[06:51:16.669] Timer tick, asking Buildbucket for the build status
[06:51:16.735] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:16.735] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:30:0) after 1m0s
[06:52:16.771] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:30:0)
[06:52:16.771] Timer tick, asking Buildbucket for the build status
[06:52:16.844] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:16.844] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:31:0) after 1m0s
[06:53:16.853] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:31:0)
[06:53:16.853] Timer tick, asking Buildbucket for the build status
[06:53:17.057] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:17.057] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:32:0) after 1m0s
[06:54:17.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:32:0)
[06:54:17.079] Timer tick, asking Buildbucket for the build status
[06:54:17.265] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:17.265] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:33:0) after 1m0s
[06:55:17.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:33:0)
[06:55:17.306] Timer tick, asking Buildbucket for the build status
[06:55:17.357] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:17.357] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:34:0) after 1m0s
[06:56:17.380] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:34:0)
[06:56:17.380] Timer tick, asking Buildbucket for the build status
[06:56:17.460] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:17.460] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:35:0) after 1m0s
[06:57:17.503] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:35:0)
[06:57:17.503] Timer tick, asking Buildbucket for the build status
[06:57:17.591] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:17.591] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:36:0) after 1m0s
[06:58:17.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:36:0)
[06:58:17.644] Timer tick, asking Buildbucket for the build status
[06:58:17.719] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:17.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:37:0) after 1m0s
[06:59:17.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:37:0)
[06:59:17.750] Timer tick, asking Buildbucket for the build status
[06:59:17.865] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:17.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:38:0) after 1m0s
[07:00:17.847] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:38:0)
[07:00:17.847] Timer tick, asking Buildbucket for the build status
[07:00:17.934] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:17.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:39:0) after 1m0s
[07:01:19.015] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:39:0)
[07:01:19.015] Timer tick, asking Buildbucket for the build status
[07:01:19.078] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:19.078] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:40:0) after 1m0s
[07:02:19.168] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:40:0)
[07:02:19.168] Timer tick, asking Buildbucket for the build status
[07:02:19.436] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:19.436] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:41:0) after 1m0s
[07:03:19.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:41:0)
[07:03:19.460] Timer tick, asking Buildbucket for the build status
[07:03:19.514] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:42:0) after 1m0s
[07:04:19.542] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:42:0)
[07:04:19.542] Timer tick, asking Buildbucket for the build status
[07:04:19.838] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:19.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:43:0) after 1m0s
[07:05:19.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:43:0)
[07:05:19.859] Timer tick, asking Buildbucket for the build status
[07:05:19.935] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:19.935] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:44:0) after 1m0s
[07:06:20.058] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:44:0)
[07:06:20.058] Timer tick, asking Buildbucket for the build status
[07:06:20.219] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:20.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:45:0) after 1m0s
[07:07:20.332] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:45:0)
[07:07:20.332] Timer tick, asking Buildbucket for the build status
[07:07:20.436] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:20.436] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:46:0) after 1m0s
[07:08:20.605] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:46:0)
[07:08:20.605] Timer tick, asking Buildbucket for the build status
[07:08:20.667] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:47:0) after 1m0s
[07:09:20.858] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:47:0)
[07:09:20.858] Timer tick, asking Buildbucket for the build status
[07:09:21.031] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:21.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:48:0) after 1m0s
[07:10:21.146] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:48:0)
[07:10:21.146] Timer tick, asking Buildbucket for the build status
[07:10:21.203] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:21.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:49:0) after 1m0s
[07:11:21.345] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:49:0)
[07:11:21.345] Timer tick, asking Buildbucket for the build status
[07:11:21.466] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:21.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:50:0) after 1m0s
[07:12:21.629] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:50:0)
[07:12:21.629] Timer tick, asking Buildbucket for the build status
[07:12:21.762] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:21.762] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:51:0) after 1m0s
[07:13:21.950] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:51:0)
[07:13:21.950] Timer tick, asking Buildbucket for the build status
[07:13:22.076] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:22.076] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:52:0) after 1m0s
[07:14:22.149] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:52:0)
[07:14:22.149] Timer tick, asking Buildbucket for the build status
[07:14:22.508] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:22.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:53:0) after 1m0s
[07:15:22.535] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:53:0)
[07:15:22.535] Timer tick, asking Buildbucket for the build status
[07:15:22.700] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:54:0) after 1m0s
[07:16:22.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:54:0)
[07:16:22.757] Timer tick, asking Buildbucket for the build status
[07:16:23.419] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:23.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:55:0) after 1m0s
[07:17:23.433] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:55:0)
[07:17:23.434] Timer tick, asking Buildbucket for the build status
[07:17:23.549] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:23.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:56:0) after 1m0s
[07:18:23.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:56:0)
[07:18:23.610] Timer tick, asking Buildbucket for the build status
[07:18:23.691] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:23.691] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:57:0) after 1m0s
[07:19:23.748] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:57:0)
[07:19:23.748] Timer tick, asking Buildbucket for the build status
[07:19:23.928] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:23.928] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:58:0) after 1m0s
[07:20:23.958] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:58:0)
[07:20:23.958] Timer tick, asking Buildbucket for the build status
[07:20:24.061] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:24.061] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:59:0) after 1m0s
[07:21:24.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:59:0)
[07:21:24.083] Timer tick, asking Buildbucket for the build status
[07:21:24.302] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:24.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:60:0) after 1m0s
[07:22:24.339] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:60:0)
[07:22:24.339] Timer tick, asking Buildbucket for the build status
[07:22:24.644] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:24.644] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:61:0) after 1m0s
[07:23:24.738] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:61:0)
[07:23:24.738] Timer tick, asking Buildbucket for the build status
[07:23:24.804] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:24.804] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:62:0) after 1m0s
[07:24:24.852] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:62:0)
[07:24:24.852] Timer tick, asking Buildbucket for the build status
[07:24:24.954] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:24.954] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:63:0) after 1m0s
[07:25:25.000] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:63:0)
[07:25:25.000] Timer tick, asking Buildbucket for the build status
[07:25:25.151] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:25.151] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:64:0) after 1m0s
[07:26:25.181] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:64:0)
[07:26:25.181] Timer tick, asking Buildbucket for the build status
[07:26:25.315] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:25.315] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:65:0) after 1m0s
[07:27:25.353] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:65:0)
[07:27:25.354] Timer tick, asking Buildbucket for the build status
[07:27:25.583] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:25.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:66:0) after 1m0s
[07:28:25.633] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:66:0)
[07:28:25.633] Timer tick, asking Buildbucket for the build status
[07:28:25.871] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:25.871] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:67:0) after 1m0s
[07:29:25.898] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:67:0)
[07:29:25.898] Timer tick, asking Buildbucket for the build status
[07:29:26.415] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:26.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:68:0) after 1m0s
[07:30:26.534] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:68:0)
[07:30:26.534] Timer tick, asking Buildbucket for the build status
[07:30:26.661] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:26.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:69:0) after 1m0s
[07:31:26.688] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:69:0)
[07:31:26.688] Timer tick, asking Buildbucket for the build status
[07:31:26.796] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:26.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:70:0) after 1m0s
[07:32:26.811] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:70:0)
[07:32:26.811] Timer tick, asking Buildbucket for the build status
[07:32:26.893] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:26.893] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:71:0) after 1m0s
[07:33:26.942] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:71:0)
[07:33:26.942] Timer tick, asking Buildbucket for the build status
[07:33:27.003] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:27.003] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:72:0) after 1m0s
[07:34:27.006] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:72:0)
[07:34:27.006] Timer tick, asking Buildbucket for the build status
[07:34:27.106] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:27.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:73:0) after 1m0s
[07:35:27.090] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:73:0)
[07:35:27.090] Timer tick, asking Buildbucket for the build status
[07:35:27.168] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:27.168] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:74:0) after 1m0s
[07:36:27.223] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:74:0)
[07:36:27.223] Timer tick, asking Buildbucket for the build status
[07:36:27.418] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:27.418] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:75:0) after 1m0s
[07:37:27.494] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:75:0)
[07:37:27.494] Timer tick, asking Buildbucket for the build status
[07:37:27.559] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:27.559] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:76:0) after 1m0s
[07:38:27.582] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:76:0)
[07:38:27.582] Timer tick, asking Buildbucket for the build status
[07:38:27.636] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:27.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:77:0) after 1m0s
[07:39:27.698] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:77:0)
[07:39:27.698] Timer tick, asking Buildbucket for the build status
[07:39:27.870] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:27.870] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:78:0) after 1m0s
[07:40:27.948] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:78:0)
[07:40:27.948] Timer tick, asking Buildbucket for the build status
[07:40:28.013] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:28.013] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:79:0) after 1m0s
[07:41:28.057] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:79:0)
[07:41:28.057] Timer tick, asking Buildbucket for the build status
[07:41:28.117] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:28.117] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:80:0) after 1m0s
[07:42:28.148] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:80:0)
[07:42:28.149] Timer tick, asking Buildbucket for the build status
[07:42:28.219] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:28.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:81:0) after 1m0s
[07:43:28.289] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:81:0)
[07:43:28.289] Timer tick, asking Buildbucket for the build status
[07:43:28.607] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:28.607] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:82:0) after 1m0s
[07:44:28.629] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:82:0)
[07:44:28.629] Timer tick, asking Buildbucket for the build status
[07:44:29.003] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:83:0) after 1m0s
[07:45:29.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:83:0)
[07:45:29.079] Timer tick, asking Buildbucket for the build status
[07:45:29.133] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:84:0) after 1m0s
[07:46:29.272] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:84:0)
[07:46:29.272] Timer tick, asking Buildbucket for the build status
[07:46:29.405] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:29.405] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:85:0) after 1m0s
[07:47:29.476] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:85:0)
[07:47:29.476] Timer tick, asking Buildbucket for the build status
[07:47:29.560] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:29.560] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:86:0) after 1m0s
[07:48:29.726] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:86:0)
[07:48:29.726] Timer tick, asking Buildbucket for the build status
[07:48:29.770] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:29.770] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:87:0) after 1m0s
[07:49:29.806] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:87:0)
[07:49:29.806] Timer tick, asking Buildbucket for the build status
[07:49:30.000] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:30.000] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:88:0) after 1m0s
[07:50:30.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:88:0)
[07:50:30.061] Timer tick, asking Buildbucket for the build status
[07:50:30.215] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:30.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:89:0) after 1m0s
[07:51:30.307] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:89:0)
[07:51:30.307] Timer tick, asking Buildbucket for the build status
[07:51:30.370] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:30.370] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:90:0) after 1m0s
[07:52:30.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:90:0)
[07:52:30.477] Timer tick, asking Buildbucket for the build status
[07:52:30.564] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:30.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:91:0) after 1m0s
[07:53:30.590] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:91:0)
[07:53:30.590] Timer tick, asking Buildbucket for the build status
[07:53:30.647] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:30.647] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:92:0) after 1m0s
[07:54:30.753] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:92:0)
[07:54:30.753] Timer tick, asking Buildbucket for the build status
[07:54:30.856] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:30.856] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:93:0) after 1m0s
[07:55:30.885] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:93:0)
[07:55:30.885] Timer tick, asking Buildbucket for the build status
[07:55:30.952] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:30.952] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:94:0) after 1m0s
[07:56:31.008] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:94:0)
[07:56:31.008] Timer tick, asking Buildbucket for the build status
[07:56:31.067] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:31.067] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:95:0) after 1m0s
[07:57:31.126] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:95:0)
[07:57:31.126] Timer tick, asking Buildbucket for the build status
[07:57:31.203] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:31.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:96:0) after 1m0s
[07:58:31.197] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:96:0)
[07:58:31.197] Timer tick, asking Buildbucket for the build status
[07:58:31.254] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:31.254] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:97:0) after 1m0s
[07:59:31.340] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:97:0)
[07:59:31.340] Timer tick, asking Buildbucket for the build status
[07:59:31.441] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:31.441] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:98:0) after 1m0s
[08:00:31.470] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:98:0)
[08:00:31.470] Timer tick, asking Buildbucket for the build status
[08:00:31.565] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:31.565] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:99:0) after 1m0s
[08:01:31.620] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:99:0)
[08:01:31.620] Timer tick, asking Buildbucket for the build status
[08:01:31.750] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:31.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:100:0) after 1m0s
[08:02:31.788] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:100:0)
[08:02:31.788] Timer tick, asking Buildbucket for the build status
[08:02:31.838] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:31.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:101:0) after 1m0s
[08:03:31.833] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:101:0)
[08:03:31.833] Timer tick, asking Buildbucket for the build status
[08:03:31.919] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:31.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:102:0) after 1m0s
[08:04:31.994] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:102:0)
[08:04:31.994] Timer tick, asking Buildbucket for the build status
[08:04:32.564] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:32.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:103:0) after 1m0s
[08:05:32.578] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:103:0)
[08:05:32.578] Timer tick, asking Buildbucket for the build status
[08:05:32.633] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:32.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:104:0) after 1m0s
[08:06:32.654] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:104:0)
[08:06:32.654] Timer tick, asking Buildbucket for the build status
[08:06:32.822] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:32.822] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:105:0) after 1m0s
[08:07:34.724] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:105:0)
[08:07:34.724] Timer tick, asking Buildbucket for the build status
[08:07:34.782] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:34.782] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:106:0) after 1m0s
[08:08:34.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:106:0)
[08:08:34.869] Timer tick, asking Buildbucket for the build status
[08:08:34.963] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:34.963] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:107:0) after 1m0s
[08:09:35.046] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:107:0)
[08:09:35.046] Timer tick, asking Buildbucket for the build status
[08:09:35.180] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:35.180] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:108:0) after 1m0s
[08:10:35.260] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:108:0)
[08:10:35.260] Timer tick, asking Buildbucket for the build status
[08:10:35.328] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:35.328] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:109:0) after 1m0s
[08:11:35.355] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:109:0)
[08:11:35.355] Timer tick, asking Buildbucket for the build status
[08:11:35.514] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:35.515] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:110:0) after 1m0s
[08:12:35.562] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:110:0)
[08:12:35.562] Timer tick, asking Buildbucket for the build status
[08:12:35.626] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:35.626] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:111:0) after 1m0s
[08:13:35.688] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:111:0)
[08:13:35.688] Timer tick, asking Buildbucket for the build status
[08:13:35.766] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:35.766] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:112:0) after 1m0s
[08:14:35.803] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:112:0)
[08:14:35.803] Timer tick, asking Buildbucket for the build status
[08:14:36.115] Build 8858242092676473360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:36.115] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023672154599769280:113:0) after 1m0s
[08:14:59.015] Received PubSub notification, asking Buildbucket for the build status
[08:14:59.083] Build 8858242092676473360: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:14:59.083] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610525698513817",
"created_by": "project:chromium",
"created_ts": "1610519049913378",
"failure_reason": "BUILD_FAILURE",
"id": "8858242092676473360",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed.\"}, \"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\": \"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-0-s4k1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7242, \"got_angle_revision\": \"2ed9671a0dddaab9c2582b62d3e479895ee0d333\", \"got_dawn_revision\": \"7faa362ea9c2bffbb8e9cc2e2d9f4f4af2fe4dde\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\", \"got_revision_cp\": \"refs/heads/master@{#842902}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"1e60bce4522aa7c92b1010988f8815f96c8243b7\", \"got_v8_revision_cp\": \"refs/heads/8.9.257@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7\", \"swarm_hashes_refs/heads/master(at){#842902}_without_patch\": {\"android_browsertests\": \"0e7133a305c6f3e27f64f40e8c685acef489384f\", \"chrome_public_test_apk\": \"bca1a371748ebb86559c6afa265d961644b2eb35\", \"content_browsertests\": \"af4aad58395d6f0b18db834daa4ab245265f431e\", \"content_shell_test_apk\": \"e5a6d7ff37f6b3f13def142d7893c8dc86a3b24e\", \"content_unittests\": \"2cab6faf03600b663d4b70451540b59a6c3d09a1\", \"unit_tests\": \"8129943468b591e62e082bbd70ce5d83deb369c6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_541cf43b1bca5745fae9eb808cc6f212921580c436c428e330e275f831e0e73a_v2\", \"builder_be71ec2e45ff41f29ecdc3611cfb7c276cc0ff3138424d653aa2b7cfb51218d3_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_fe5305c96c631c9bcc74a68d877d31a94a8d3c28663b28d2fadfc8f798de4ec0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-0-s4k1\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610519159478875",
"status": "COMPLETED",
"status_changed_ts": "1610519160079032",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7242",
"builder:android-bfcache-rel",
"buildset:commit/git/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dec402fc4d5c6c74c4943b8ddaa69bed576e6f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023672154599769280",
"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/8858242092676473360/+/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:51133c0390ebee10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610525698660130",
"url": "https://ci.chromium.org/b/8858242092676473360",
"utcnow_ts": "1610525699073298"
}
}
[08:14:59.083] Invocation finished in 1h50m50.388801246s with status FAILED