[19:35:23.026] New invocation is queued and will start shortly
[19:35:24.252] Starting the invocation (attempt 1)
[19:35:24.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:35:24.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:35:24.420] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033950428351269504",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9033950428351269504",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"gitiles_ref:refs/heads/master"
]
}
[19:35:25.858] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1600716924604067",
"id": "8868520366024236576",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600716925627558",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5155",
"builder:android-bfcache-rel",
"buildset:commit/git/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033950428351269504",
"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/8868520366024236576/+/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": "1600716925627721",
"url": "https://ci.chromium.org/b/8868520366024236576",
"utcnow_ts": "1600716925846611"
}
}
[19:35:25.858] Task URL: https://ci.chromium.org/b/8868520366024236576
[19:35:25.858] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:2:0) after 1m0s
[19:36:26.124] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:2:0)
[19:36:26.124] Timer tick, asking Buildbucket for the build status
[19:36:26.647] Build 8868520366024236576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:26.647] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:3:0) after 1m0s
[19:37:26.754] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:3:0)
[19:37:26.754] Timer tick, asking Buildbucket for the build status
[19:37:27.134] Build 8868520366024236576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:27.134] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:4:0) after 1m0s
[19:38:27.187] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:4:0)
[19:38:27.188] Timer tick, asking Buildbucket for the build status
[19:38:27.617] Build 8868520366024236576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:27.617] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:5:0) after 1m0s
[19:39:27.786] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:5:0)
[19:39:27.786] Timer tick, asking Buildbucket for the build status
[19:39:27.966] Build 8868520366024236576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:27.966] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:6:0) after 1m0s
[19:40:10.580] Received PubSub notification, asking Buildbucket for the build status
[19:40:11.744] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:27.990] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:6:0)
[19:40:27.990] Timer tick, asking Buildbucket for the build status
[19:40:28.809] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:28.809] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:8:0) after 1m0s
[19:41:29.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:8:0)
[19:41:29.129] Timer tick, asking Buildbucket for the build status
[19:41:29.939] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:29.939] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:9:0) after 1m0s
[19:42:29.994] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:9:0)
[19:42:29.994] Timer tick, asking Buildbucket for the build status
[19:42:30.331] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:30.331] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:10:0) after 1m0s
[19:43:30.549] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:10:0)
[19:43:30.549] Timer tick, asking Buildbucket for the build status
[19:43:30.739] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:30.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:11:0) after 1m0s
[19:44:30.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:11:0)
[19:44:30.784] Timer tick, asking Buildbucket for the build status
[19:44:31.052] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:31.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:12:0) after 1m0s
[19:45:31.157] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:12:0)
[19:45:31.157] Timer tick, asking Buildbucket for the build status
[19:45:31.505] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:31.505] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:13:0) after 1m0s
[19:46:31.605] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:13:0)
[19:46:31.605] Timer tick, asking Buildbucket for the build status
[19:46:31.907] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:31.907] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:14:0) after 1m0s
[19:47:31.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:14:0)
[19:47:31.941] Timer tick, asking Buildbucket for the build status
[19:47:32.933] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:32.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:15:0) after 1m0s
[19:48:32.939] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:15:0)
[19:48:32.939] Timer tick, asking Buildbucket for the build status
[19:48:33.335] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:33.335] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:16:0) after 1m0s
[19:49:33.410] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:16:0)
[19:49:33.410] Timer tick, asking Buildbucket for the build status
[19:49:33.532] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:33.532] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:17:0) after 1m0s
[19:50:33.852] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:17:0)
[19:50:33.852] Timer tick, asking Buildbucket for the build status
[19:50:34.318] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:34.318] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:18:0) after 1m0s
[19:51:34.526] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:18:0)
[19:51:34.526] Timer tick, asking Buildbucket for the build status
[19:51:34.693] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:34.693] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:19:0) after 1m0s
[19:52:34.947] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:19:0)
[19:52:34.947] Timer tick, asking Buildbucket for the build status
[19:52:35.033] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:35.033] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:20:0) after 1m0s
[19:53:35.267] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:20:0)
[19:53:35.267] Timer tick, asking Buildbucket for the build status
[19:53:35.407] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:35.407] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:21:0) after 1m0s
[19:54:35.649] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:21:0)
[19:54:35.649] Timer tick, asking Buildbucket for the build status
[19:54:35.729] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:35.729] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:22:0) after 1m0s
[19:55:35.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:22:0)
[19:55:35.757] Timer tick, asking Buildbucket for the build status
[19:55:39.910] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:39.910] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:23:0) after 1m0s
[19:56:40.001] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:23:0)
[19:56:40.001] Timer tick, asking Buildbucket for the build status
[19:56:40.133] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:40.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:24:0) after 1m0s
[19:57:40.188] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:24:0)
[19:57:40.188] Timer tick, asking Buildbucket for the build status
[19:57:40.464] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:40.464] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:25:0) after 1m0s
[19:58:40.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:25:0)
[19:58:40.493] Timer tick, asking Buildbucket for the build status
[19:58:40.715] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:40.715] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:26:0) after 1m0s
[19:59:40.769] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:26:0)
[19:59:40.769] Timer tick, asking Buildbucket for the build status
[19:59:40.945] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:40.945] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:27:0) after 1m0s
[20:00:41.050] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:27:0)
[20:00:41.050] Timer tick, asking Buildbucket for the build status
[20:00:41.488] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:41.488] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:28:0) after 1m0s
[20:01:41.627] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:28:0)
[20:01:41.627] Timer tick, asking Buildbucket for the build status
[20:01:42.444] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:42.444] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:29:0) after 1m0s
[20:02:42.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:29:0)
[20:02:42.528] Timer tick, asking Buildbucket for the build status
[20:02:42.839] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:42.839] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:30:0) after 1m0s
[20:03:42.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:30:0)
[20:03:42.915] Timer tick, asking Buildbucket for the build status
[20:03:43.533] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:43.533] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:31:0) after 1m0s
[20:04:43.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:31:0)
[20:04:43.609] Timer tick, asking Buildbucket for the build status
[20:04:43.821] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:43.821] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:32:0) after 1m0s
[20:05:43.872] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:32:0)
[20:05:43.872] Timer tick, asking Buildbucket for the build status
[20:05:44.132] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:44.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:33:0) after 1m0s
[20:06:44.193] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:33:0)
[20:06:44.193] Timer tick, asking Buildbucket for the build status
[20:06:44.416] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:44.416] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:34:0) after 1m0s
[20:07:44.470] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:34:0)
[20:07:44.470] Timer tick, asking Buildbucket for the build status
[20:07:44.621] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:44.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:35:0) after 1m0s
[20:08:44.690] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:35:0)
[20:08:44.690] Timer tick, asking Buildbucket for the build status
[20:08:44.894] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:44.894] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:36:0) after 1m0s
[20:09:45.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:36:0)
[20:09:45.021] Timer tick, asking Buildbucket for the build status
[20:09:45.227] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:45.227] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:37:0) after 1m0s
[20:10:45.293] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:37:0)
[20:10:45.293] Timer tick, asking Buildbucket for the build status
[20:10:46.216] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:46.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:38:0) after 1m0s
[20:11:46.269] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:38:0)
[20:11:46.269] Timer tick, asking Buildbucket for the build status
[20:11:46.406] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:39:0) after 1m0s
[20:12:46.505] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:39:0)
[20:12:46.505] Timer tick, asking Buildbucket for the build status
[20:12:46.622] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:46.622] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:40:0) after 1m0s
[20:13:46.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:40:0)
[20:13:46.716] Timer tick, asking Buildbucket for the build status
[20:13:46.903] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:46.903] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:41:0) after 1m0s
[20:14:46.992] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:41:0)
[20:14:46.992] Timer tick, asking Buildbucket for the build status
[20:14:47.424] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:47.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:42:0) after 1m0s
[20:15:47.558] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:42:0)
[20:15:47.558] Timer tick, asking Buildbucket for the build status
[20:15:48.343] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:48.343] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:43:0) after 1m0s
[20:16:48.507] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:43:0)
[20:16:48.507] Timer tick, asking Buildbucket for the build status
[20:16:49.194] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:49.194] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:44:0) after 1m0s
[20:17:49.248] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:44:0)
[20:17:49.248] Timer tick, asking Buildbucket for the build status
[20:17:49.411] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:49.411] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:45:0) after 1m0s
[20:18:49.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:45:0)
[20:18:49.519] Timer tick, asking Buildbucket for the build status
[20:18:50.226] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:50.226] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:46:0) after 1m0s
[20:19:50.248] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:46:0)
[20:19:50.248] Timer tick, asking Buildbucket for the build status
[20:19:50.625] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:50.625] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:47:0) after 1m0s
[20:20:50.728] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:47:0)
[20:20:50.728] Timer tick, asking Buildbucket for the build status
[20:20:51.211] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:51.211] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:48:0) after 1m0s
[20:21:51.332] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:48:0)
[20:21:51.332] Timer tick, asking Buildbucket for the build status
[20:21:51.616] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:51.616] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:49:0) after 1m0s
[20:22:51.748] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:49:0)
[20:22:51.748] Timer tick, asking Buildbucket for the build status
[20:22:52.316] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:50:0) after 1m0s
[20:23:52.671] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:50:0)
[20:23:52.671] Timer tick, asking Buildbucket for the build status
[20:23:52.936] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:52.936] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:51:0) after 1m0s
[20:24:52.983] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:51:0)
[20:24:52.983] Timer tick, asking Buildbucket for the build status
[20:24:53.317] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:53.318] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:52:0) after 1m0s
[20:25:53.490] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:52:0)
[20:25:53.490] Timer tick, asking Buildbucket for the build status
[20:25:54.201] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:53:0) after 1m0s
[20:26:54.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:53:0)
[20:26:54.375] Timer tick, asking Buildbucket for the build status
[20:26:55.971] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:55.971] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:54:0) after 1m0s
[20:27:56.055] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:54:0)
[20:27:56.055] Timer tick, asking Buildbucket for the build status
[20:27:56.324] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:55:0) after 1m0s
[20:28:56.499] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:55:0)
[20:28:56.499] Timer tick, asking Buildbucket for the build status
[20:28:58.244] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:58.244] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:56:0) after 1m0s
[20:29:58.506] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:56:0)
[20:29:58.506] Timer tick, asking Buildbucket for the build status
[20:29:58.897] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:58.897] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:57:0) after 1m0s
[20:30:59.023] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:57:0)
[20:30:59.023] Timer tick, asking Buildbucket for the build status
[20:30:59.416] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:59.416] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:58:0) after 1m0s
[20:31:59.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:58:0)
[20:31:59.479] Timer tick, asking Buildbucket for the build status
[20:32:00.401] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:00.401] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:59:0) after 1m0s
[20:33:00.536] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:59:0)
[20:33:00.536] Timer tick, asking Buildbucket for the build status
[20:33:01.170] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:01.173] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:60:0) after 1m0s
[20:34:02.042] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:60:0)
[20:34:02.042] Timer tick, asking Buildbucket for the build status
[20:34:02.313] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:61:0) after 1m0s
[20:35:03.178] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:61:0)
[20:35:03.178] Timer tick, asking Buildbucket for the build status
[20:35:03.621] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:62:0) after 1m0s
[20:36:03.679] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:62:0)
[20:36:03.679] Timer tick, asking Buildbucket for the build status
[20:36:03.909] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:03.909] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:63:0) after 1m0s
[20:37:03.999] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:63:0)
[20:37:03.999] Timer tick, asking Buildbucket for the build status
[20:37:04.313] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:04.313] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:64:0) after 1m0s
[20:38:04.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:64:0)
[20:38:04.493] Timer tick, asking Buildbucket for the build status
[20:38:04.806] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:04.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:65:0) after 1m0s
[20:39:04.913] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:65:0)
[20:39:04.913] Timer tick, asking Buildbucket for the build status
[20:39:05.106] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:05.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:66:0) after 1m0s
[20:40:05.197] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:66:0)
[20:40:05.197] Timer tick, asking Buildbucket for the build status
[20:40:05.555] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:05.555] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:67:0) after 1m0s
[20:41:05.704] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:67:0)
[20:41:05.704] Timer tick, asking Buildbucket for the build status
[20:41:06.204] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:06.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:68:0) after 1m0s
[20:42:06.249] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:68:0)
[20:42:06.249] Timer tick, asking Buildbucket for the build status
[20:42:06.599] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:06.599] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:69:0) after 1m0s
[20:43:06.660] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:69:0)
[20:43:06.660] Timer tick, asking Buildbucket for the build status
[20:43:07.180] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:07.180] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:70:0) after 1m0s
[20:44:07.228] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:70:0)
[20:44:07.228] Timer tick, asking Buildbucket for the build status
[20:44:07.422] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:07.422] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:71:0) after 1m0s
[20:45:07.484] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:71:0)
[20:45:07.484] Timer tick, asking Buildbucket for the build status
[20:45:08.204] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:08.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:72:0) after 1m0s
[20:46:08.332] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:72:0)
[20:46:08.332] Timer tick, asking Buildbucket for the build status
[20:46:09.031] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:73:0) after 1m0s
[20:47:09.271] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:73:0)
[20:47:09.271] Timer tick, asking Buildbucket for the build status
[20:47:10.500] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:10.500] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:74:0) after 1m0s
[20:48:10.525] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:74:0)
[20:48:10.525] Timer tick, asking Buildbucket for the build status
[20:48:10.730] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:10.730] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:75:0) after 1m0s
[20:49:10.850] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:75:0)
[20:49:10.850] Timer tick, asking Buildbucket for the build status
[20:49:11.570] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:11.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:76:0) after 1m0s
[20:50:11.687] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:76:0)
[20:50:11.687] Timer tick, asking Buildbucket for the build status
[20:50:12.012] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:12.012] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:77:0) after 1m0s
[20:51:12.177] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:77:0)
[20:51:12.177] Timer tick, asking Buildbucket for the build status
[20:51:12.891] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:12.891] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:78:0) after 1m0s
[20:52:12.966] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:78:0)
[20:52:12.966] Timer tick, asking Buildbucket for the build status
[20:52:13.129] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:79:0) after 1m0s
[20:53:13.197] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:79:0)
[20:53:13.197] Timer tick, asking Buildbucket for the build status
[20:53:16.920] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:16.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:80:0) after 1m0s
[20:54:17.178] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:80:0)
[20:54:17.178] Timer tick, asking Buildbucket for the build status
[20:54:17.531] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:17.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:81:0) after 1m0s
[20:55:17.637] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:81:0)
[20:55:17.637] Timer tick, asking Buildbucket for the build status
[20:55:17.898] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:17.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:82:0) after 1m0s
[20:56:17.968] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:82:0)
[20:56:17.968] Timer tick, asking Buildbucket for the build status
[20:56:18.401] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:18.401] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:83:0) after 1m0s
[20:57:18.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:83:0)
[20:57:18.479] Timer tick, asking Buildbucket for the build status
[20:57:18.871] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:18.871] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:84:0) after 1m0s
[20:58:19.018] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:84:0)
[20:58:19.018] Timer tick, asking Buildbucket for the build status
[20:58:19.397] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:19.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:85:0) after 1m0s
[20:59:19.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:85:0)
[20:59:19.428] Timer tick, asking Buildbucket for the build status
[20:59:20.297] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:20.297] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:86:0) after 1m0s
[21:00:20.413] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:86:0)
[21:00:20.413] Timer tick, asking Buildbucket for the build status
[21:00:21.002] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:21.002] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:87:0) after 1m0s
[21:01:21.081] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:87:0)
[21:01:21.081] Timer tick, asking Buildbucket for the build status
[21:01:21.731] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:21.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:88:0) after 1m0s
[21:02:21.895] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:88:0)
[21:02:21.895] Timer tick, asking Buildbucket for the build status
[21:02:22.412] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:22.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:89:0) after 1m0s
[21:03:22.546] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:89:0)
[21:03:22.546] Timer tick, asking Buildbucket for the build status
[21:03:22.661] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:22.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:90:0) after 1m0s
[21:04:22.742] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:90:0)
[21:04:22.742] Timer tick, asking Buildbucket for the build status
[21:04:22.803] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:22.803] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:91:0) after 1m0s
[21:05:22.870] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:91:0)
[21:05:22.870] Timer tick, asking Buildbucket for the build status
[21:05:23.018] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:23.018] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:92:0) after 1m0s
[21:06:23.224] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:92:0)
[21:06:23.224] Timer tick, asking Buildbucket for the build status
[21:06:23.696] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:23.696] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:93:0) after 1m0s
[21:07:23.838] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:93:0)
[21:07:23.839] Timer tick, asking Buildbucket for the build status
[21:07:24.108] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:24.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:94:0) after 1m0s
[21:08:24.265] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:94:0)
[21:08:24.265] Timer tick, asking Buildbucket for the build status
[21:08:28.034] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:28.035] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:95:0) after 1m0s
[21:09:28.327] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:95:0)
[21:09:28.327] Timer tick, asking Buildbucket for the build status
[21:09:28.631] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:28.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:96:0) after 1m0s
[21:10:28.689] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:96:0)
[21:10:28.689] Timer tick, asking Buildbucket for the build status
[21:10:28.962] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:28.962] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:97:0) after 1m0s
[21:11:29.092] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:97:0)
[21:11:29.092] Timer tick, asking Buildbucket for the build status
[21:11:29.324] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:29.324] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:98:0) after 1m0s
[21:12:29.357] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:98:0)
[21:12:29.357] Timer tick, asking Buildbucket for the build status
[21:12:29.819] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:29.819] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:99:0) after 1m0s
[21:13:29.865] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:99:0)
[21:13:29.865] Timer tick, asking Buildbucket for the build status
[21:13:30.108] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:30.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:100:0) after 1m0s
[21:14:30.226] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:100:0)
[21:14:30.226] Timer tick, asking Buildbucket for the build status
[21:14:30.414] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:101:0) after 1m0s
[21:15:30.472] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:101:0)
[21:15:30.472] Timer tick, asking Buildbucket for the build status
[21:15:30.904] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:30.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:102:0) after 1m0s
[21:16:31.115] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:102:0)
[21:16:31.116] Timer tick, asking Buildbucket for the build status
[21:16:32.693] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:32.693] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:103:0) after 1m0s
[21:17:32.850] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:103:0)
[21:17:32.850] Timer tick, asking Buildbucket for the build status
[21:17:33.108] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:33.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:104:0) after 1m0s
[21:18:33.277] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:104:0)
[21:18:33.277] Timer tick, asking Buildbucket for the build status
[21:18:33.829] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:33.829] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:105:0) after 1m0s
[21:19:33.899] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:105:0)
[21:19:33.899] Timer tick, asking Buildbucket for the build status
[21:19:34.110] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:34.110] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:106:0) after 1m0s
[21:20:34.267] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:106:0)
[21:20:34.267] Timer tick, asking Buildbucket for the build status
[21:20:36.028] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:36.028] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:107:0) after 1m0s
[21:21:36.124] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:107:0)
[21:21:36.124] Timer tick, asking Buildbucket for the build status
[21:21:36.294] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:36.294] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:108:0) after 1m0s
[21:22:36.420] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:108:0)
[21:22:36.420] Timer tick, asking Buildbucket for the build status
[21:22:36.815] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:36.815] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:109:0) after 1m0s
[21:23:36.887] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:109:0)
[21:23:36.887] Timer tick, asking Buildbucket for the build status
[21:23:37.807] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:37.807] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:110:0) after 1m0s
[21:24:37.907] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:110:0)
[21:24:37.907] Timer tick, asking Buildbucket for the build status
[21:24:38.205] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:38.206] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:111:0) after 1m0s
[21:25:38.321] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:111:0)
[21:25:38.321] Timer tick, asking Buildbucket for the build status
[21:25:39.128] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:39.128] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:112:0) after 1m0s
[21:26:39.177] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:112:0)
[21:26:39.177] Timer tick, asking Buildbucket for the build status
[21:26:39.666] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:39.666] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:113:0) after 1m0s
[21:27:39.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:113:0)
[21:27:39.750] Timer tick, asking Buildbucket for the build status
[21:27:40.113] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.113] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:114:0) after 1m0s
[21:28:40.256] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:114:0)
[21:28:40.256] Timer tick, asking Buildbucket for the build status
[21:28:40.528] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:40.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:115:0) after 1m0s
[21:29:40.632] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:115:0)
[21:29:40.632] Timer tick, asking Buildbucket for the build status
[21:29:40.927] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:40.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:116:0) after 1m0s
[21:30:41.005] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:116:0)
[21:30:41.005] Timer tick, asking Buildbucket for the build status
[21:30:41.425] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:41.425] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:117:0) after 1m0s
[21:31:41.485] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:117:0)
[21:31:41.485] Timer tick, asking Buildbucket for the build status
[21:31:41.933] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:41.933] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:118:0) after 1m0s
[21:32:41.967] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:118:0)
[21:32:41.967] Timer tick, asking Buildbucket for the build status
[21:32:42.201] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:42.201] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:119:0) after 1m0s
[21:33:42.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:119:0)
[21:33:42.233] Timer tick, asking Buildbucket for the build status
[21:33:42.331] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:42.331] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:120:0) after 1m0s
[21:34:42.456] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:120:0)
[21:34:42.456] Timer tick, asking Buildbucket for the build status
[21:34:42.621] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:42.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:121:0) after 1m0s
[21:35:42.658] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:121:0)
[21:35:42.658] Timer tick, asking Buildbucket for the build status
[21:35:43.142] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.142] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:122:0) after 1m0s
[21:36:43.184] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:122:0)
[21:36:43.184] Timer tick, asking Buildbucket for the build status
[21:36:43.403] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:43.403] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:123:0) after 1m0s
[21:37:43.414] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:123:0)
[21:37:43.414] Timer tick, asking Buildbucket for the build status
[21:37:43.701] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:43.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:124:0) after 1m0s
[21:38:43.799] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:124:0)
[21:38:43.799] Timer tick, asking Buildbucket for the build status
[21:38:44.300] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:44.300] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:125:0) after 1m0s
[21:39:44.334] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:125:0)
[21:39:44.334] Timer tick, asking Buildbucket for the build status
[21:39:44.631] Build 8868520366024236576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:44.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033950428351269504:126:0) after 1m0s
[21:40:30.164] Received PubSub notification, asking Buildbucket for the build status
[21:40:30.407] Build 8868520366024236576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:40:30.407] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1600724428990247",
"created_by": "project:chromium",
"created_ts": "1600716924604067",
"id": "8868520366024236576",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\", \"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-89-q1yg\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5155, \"got_angle_revision\": \"9f70b6b2054f8b193efe63cb1d16a4e40b91934a\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\", \"got_revision_cp\": \"refs/heads/master@{#808997}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"ef916081ffcb56e58e88cc56756eef80ce4c9215\", \"got_v8_revision_cp\": \"refs/heads/8.7.147@{#1}\", \"got_webrtc_revision\": \"d41c2a6b8aeb8a2bfec29689c68cba79638d902a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32151}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29\", \"swarm_hashes\": {\"android_browsertests\": \"110adb973aa2485fa1d12e1ce3f8753e3ea8c905\", \"chrome_public_test_apk\": \"df8ee0d19714a43c2cd5d6a877cf93ba01252b30\", \"content_browsertests\": \"65f7227422a7ae963442c1e3ab7c47650d069658\", \"content_shell_test_apk\": \"06cdda4dc9c1f11747d253f6e702cf07e5040eb1\", \"content_unittests\": \"8cb15c3578dff6565cae265318eaf7899188b48b\", \"unit_tests\": \"a45fc05d2c783aab257a891bd7d9372f194751bc\"}, \"swarm_hashes_refs/heads/master(at){#808997}_without_patch\": {\"android_browsertests\": \"110adb973aa2485fa1d12e1ce3f8753e3ea8c905\", \"chrome_public_test_apk\": \"df8ee0d19714a43c2cd5d6a877cf93ba01252b30\", \"content_browsertests\": \"65f7227422a7ae963442c1e3ab7c47650d069658\", \"content_shell_test_apk\": \"06cdda4dc9c1f11747d253f6e702cf07e5040eb1\", \"content_unittests\": \"8cb15c3578dff6565cae265318eaf7899188b48b\", \"unit_tests\": \"a45fc05d2c783aab257a891bd7d9372f194751bc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_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-89-q1yg\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5531-8ab19d9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600717208946245",
"status": "COMPLETED",
"status_changed_ts": "1600724429594983",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5155",
"builder:android-bfcache-rel",
"buildset:commit/git/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f9b3b3b7e6e0de5a04a638dbe7169fd26bd24f29",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033950428351269504",
"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/8868520366024236576/+/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:4ecafb747040ac10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600724429614527",
"url": "https://ci.chromium.org/b/8868520366024236576",
"utcnow_ts": "1600724430390721"
}
}
[21:40:30.407] Invocation finished in 2h5m7.405070997s with status SUCCEEDED