[20:04:00.719] New invocation is queued and will start shortly
[20:04:01.871] Starting the invocation (attempt 1)
[20:04:01.984] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:04:01.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:04:01.984] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078884722557054592",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"dc2f5f9ff81dbc4f963adf023fadea3404d084e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078884722557054592",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"gitiles_ref:refs/heads/master"
]
}
[20:04:04.985] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557864242443379",
"id": "8913454660073883440",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"dc2f5f9ff81dbc4f963adf023fadea3404d084e5\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557864244207768",
"tags": [
"builder:clang",
"buildset:commit/git/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078884722557054592",
"scheduler_job_id:fuchsia/prod-clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8913454660073883440/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1557864244207870",
"url": "https://ci.chromium.org/b/8913454660073883440",
"utcnow_ts": "1557864244924610"
}
}
[20:04:04.985] Task URL: https://ci.chromium.org/b/8913454660073883440
[20:04:04.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:2:0) after 1m0s
[20:04:14.928] Received PubSub notification, asking Buildbucket for the build status
[20:04:14.989] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:05.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:2:0)
[20:05:05.012] Timer tick, asking Buildbucket for the build status
[20:05:05.895] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:05.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:4:0) after 1m0s
[20:06:05.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:4:0)
[20:06:05.940] Timer tick, asking Buildbucket for the build status
[20:06:06.141] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:06.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:5:0) after 1m0s
[20:07:06.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:5:0)
[20:07:06.183] Timer tick, asking Buildbucket for the build status
[20:07:06.252] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:06.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:6:0) after 1m0s
[20:08:06.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:6:0)
[20:08:06.283] Timer tick, asking Buildbucket for the build status
[20:08:09.706] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:09.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:7:0) after 1m0s
[20:09:09.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:7:0)
[20:09:09.728] Timer tick, asking Buildbucket for the build status
[20:09:09.854] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:09.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:8:0) after 1m0s
[20:10:09.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:8:0)
[20:10:09.874] Timer tick, asking Buildbucket for the build status
[20:10:10.156] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:10.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:9:0) after 1m0s
[20:11:10.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:9:0)
[20:11:10.180] Timer tick, asking Buildbucket for the build status
[20:11:10.243] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:10.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:10:0) after 1m0s
[20:12:10.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:10:0)
[20:12:10.268] Timer tick, asking Buildbucket for the build status
[20:12:10.456] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:10.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:11:0) after 1m0s
[20:13:10.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:11:0)
[20:13:10.572] Timer tick, asking Buildbucket for the build status
[20:13:10.666] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:10.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:12:0) after 1m0s
[20:14:10.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:12:0)
[20:14:10.716] Timer tick, asking Buildbucket for the build status
[20:14:13.620] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:13.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:13:0) after 1m0s
[20:15:13.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:13:0)
[20:15:13.641] Timer tick, asking Buildbucket for the build status
[20:15:13.901] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:13.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:14:0) after 1m0s
[20:16:13.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:14:0)
[20:16:13.910] Timer tick, asking Buildbucket for the build status
[20:16:13.965] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:13.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:15:0) after 1m0s
[20:17:13.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:15:0)
[20:17:13.997] Timer tick, asking Buildbucket for the build status
[20:17:14.270] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:14.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:16:0) after 1m0s
[20:18:14.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:16:0)
[20:18:14.304] Timer tick, asking Buildbucket for the build status
[20:18:14.523] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:14.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:17:0) after 1m0s
[20:19:14.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:17:0)
[20:19:14.647] Timer tick, asking Buildbucket for the build status
[20:19:14.790] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:14.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:18:0) after 1m0s
[20:20:14.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:18:0)
[20:20:14.926] Timer tick, asking Buildbucket for the build status
[20:20:15.143] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:15.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:19:0) after 1m0s
[20:21:15.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:19:0)
[20:21:15.177] Timer tick, asking Buildbucket for the build status
[20:21:15.651] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:15.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:20:0) after 1m0s
[20:22:15.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:20:0)
[20:22:15.689] Timer tick, asking Buildbucket for the build status
[20:22:15.836] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:15.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:21:0) after 1m0s
[20:23:15.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:21:0)
[20:23:15.850] Timer tick, asking Buildbucket for the build status
[20:23:15.934] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:15.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:22:0) after 1m0s
[20:24:15.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:22:0)
[20:24:15.936] Timer tick, asking Buildbucket for the build status
[20:24:16.008] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:23:0) after 1m0s
[20:25:16.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:23:0)
[20:25:16.044] Timer tick, asking Buildbucket for the build status
[20:25:16.234] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:16.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:24:0) after 1m0s
[20:26:16.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:24:0)
[20:26:16.292] Timer tick, asking Buildbucket for the build status
[20:26:16.332] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:16.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:25:0) after 1m0s
[20:27:16.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:25:0)
[20:27:16.767] Timer tick, asking Buildbucket for the build status
[20:27:16.848] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:16.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:26:0) after 1m0s
[20:28:16.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:26:0)
[20:28:16.887] Timer tick, asking Buildbucket for the build status
[20:28:16.977] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:16.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:27:0) after 1m0s
[20:29:17.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:27:0)
[20:29:17.045] Timer tick, asking Buildbucket for the build status
[20:29:17.114] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:17.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:28:0) after 1m0s
[20:30:17.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:28:0)
[20:30:17.134] Timer tick, asking Buildbucket for the build status
[20:30:17.292] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:17.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:29:0) after 1m0s
[20:31:17.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:29:0)
[20:31:17.291] Timer tick, asking Buildbucket for the build status
[20:31:17.353] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:17.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:30:0) after 1m0s
[20:32:17.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:30:0)
[20:32:17.512] Timer tick, asking Buildbucket for the build status
[20:32:17.702] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:17.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:31:0) after 1m0s
[20:33:17.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:31:0)
[20:33:17.726] Timer tick, asking Buildbucket for the build status
[20:33:17.809] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:17.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:32:0) after 1m0s
[20:34:17.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:32:0)
[20:34:17.837] Timer tick, asking Buildbucket for the build status
[20:34:18.021] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:18.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:33:0) after 1m0s
[20:35:18.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:33:0)
[20:35:18.058] Timer tick, asking Buildbucket for the build status
[20:35:18.134] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:18.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:34:0) after 1m0s
[20:36:18.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:34:0)
[20:36:18.290] Timer tick, asking Buildbucket for the build status
[20:36:18.510] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:18.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:35:0) after 1m0s
[20:37:18.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:35:0)
[20:37:18.515] Timer tick, asking Buildbucket for the build status
[20:37:18.763] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:18.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:36:0) after 1m0s
[20:38:19.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:36:0)
[20:38:19.086] Timer tick, asking Buildbucket for the build status
[20:38:19.141] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:19.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:37:0) after 1m0s
[20:39:19.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:37:0)
[20:39:19.198] Timer tick, asking Buildbucket for the build status
[20:39:19.300] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:19.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:38:0) after 1m0s
[20:40:19.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:38:0)
[20:40:19.366] Timer tick, asking Buildbucket for the build status
[20:40:19.409] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:19.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:39:0) after 1m0s
[20:41:19.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:39:0)
[20:41:19.497] Timer tick, asking Buildbucket for the build status
[20:41:19.558] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:19.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:40:0) after 1m0s
[20:42:19.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:40:0)
[20:42:19.566] Timer tick, asking Buildbucket for the build status
[20:42:19.628] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:19.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:41:0) after 1m0s
[20:43:19.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:41:0)
[20:43:19.736] Timer tick, asking Buildbucket for the build status
[20:43:19.803] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:19.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:42:0) after 1m0s
[20:44:19.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:42:0)
[20:44:19.828] Timer tick, asking Buildbucket for the build status
[20:44:19.918] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:19.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:43:0) after 1m0s
[20:45:19.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:43:0)
[20:45:19.888] Timer tick, asking Buildbucket for the build status
[20:45:19.980] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:19.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:44:0) after 1m0s
[20:46:19.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:44:0)
[20:46:19.995] Timer tick, asking Buildbucket for the build status
[20:46:20.133] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:20.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:45:0) after 1m0s
[20:47:20.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:45:0)
[20:47:20.122] Timer tick, asking Buildbucket for the build status
[20:47:20.164] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:20.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:46:0) after 1m0s
[20:48:20.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:46:0)
[20:48:20.211] Timer tick, asking Buildbucket for the build status
[20:48:20.275] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:20.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:47:0) after 1m0s
[20:49:20.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:47:0)
[20:49:20.280] Timer tick, asking Buildbucket for the build status
[20:49:20.356] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:20.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:48:0) after 1m0s
[20:50:20.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:48:0)
[20:50:20.362] Timer tick, asking Buildbucket for the build status
[20:50:20.417] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:20.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:49:0) after 1m0s
[20:51:20.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:49:0)
[20:51:20.446] Timer tick, asking Buildbucket for the build status
[20:51:20.520] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:20.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:50:0) after 1m0s
[20:52:20.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:50:0)
[20:52:20.541] Timer tick, asking Buildbucket for the build status
[20:52:20.651] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:20.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:51:0) after 1m0s
[20:53:21.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:51:0)
[20:53:21.206] Timer tick, asking Buildbucket for the build status
[20:53:21.302] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:21.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:52:0) after 1m0s
[20:54:21.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:52:0)
[20:54:21.569] Timer tick, asking Buildbucket for the build status
[20:54:21.632] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:21.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:53:0) after 1m0s
[20:55:21.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:53:0)
[20:55:21.696] Timer tick, asking Buildbucket for the build status
[20:55:21.745] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:21.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:54:0) after 1m0s
[20:56:21.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:54:0)
[20:56:21.786] Timer tick, asking Buildbucket for the build status
[20:56:22.048] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:22.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:55:0) after 1m0s
[20:57:22.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:55:0)
[20:57:22.094] Timer tick, asking Buildbucket for the build status
[20:57:22.363] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:22.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:56:0) after 1m0s
[20:58:22.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:56:0)
[20:58:22.383] Timer tick, asking Buildbucket for the build status
[20:58:22.460] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:22.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:57:0) after 1m0s
[20:59:22.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:57:0)
[20:59:22.492] Timer tick, asking Buildbucket for the build status
[20:59:22.607] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:22.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:58:0) after 1m0s
[21:00:22.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:58:0)
[21:00:22.688] Timer tick, asking Buildbucket for the build status
[21:00:22.801] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:22.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:59:0) after 1m0s
[21:01:22.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:59:0)
[21:01:22.961] Timer tick, asking Buildbucket for the build status
[21:01:23.010] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:23.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:60:0) after 1m0s
[21:02:23.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:60:0)
[21:02:23.253] Timer tick, asking Buildbucket for the build status
[21:02:23.503] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:23.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:61:0) after 1m0s
[21:03:23.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:61:0)
[21:03:23.591] Timer tick, asking Buildbucket for the build status
[21:03:23.691] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:23.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:62:0) after 1m0s
[21:04:23.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:62:0)
[21:04:23.745] Timer tick, asking Buildbucket for the build status
[21:04:24.151] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:24.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:63:0) after 1m0s
[21:05:24.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:63:0)
[21:05:24.251] Timer tick, asking Buildbucket for the build status
[21:05:24.343] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:24.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:64:0) after 1m0s
[21:06:24.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:64:0)
[21:06:24.331] Timer tick, asking Buildbucket for the build status
[21:06:24.419] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:24.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:65:0) after 1m0s
[21:07:24.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:65:0)
[21:07:24.454] Timer tick, asking Buildbucket for the build status
[21:07:24.514] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:24.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:66:0) after 1m0s
[21:08:24.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:66:0)
[21:08:24.514] Timer tick, asking Buildbucket for the build status
[21:08:24.656] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:24.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:67:0) after 1m0s
[21:09:24.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:67:0)
[21:09:24.762] Timer tick, asking Buildbucket for the build status
[21:09:24.845] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:24.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:68:0) after 1m0s
[21:10:24.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:68:0)
[21:10:24.876] Timer tick, asking Buildbucket for the build status
[21:10:24.937] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:24.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:69:0) after 1m0s
[21:11:24.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:69:0)
[21:11:24.923] Timer tick, asking Buildbucket for the build status
[21:11:24.968] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:24.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:70:0) after 1m0s
[21:12:25.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:70:0)
[21:12:25.019] Timer tick, asking Buildbucket for the build status
[21:12:25.075] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:25.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:71:0) after 1m0s
[21:13:25.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:71:0)
[21:13:25.067] Timer tick, asking Buildbucket for the build status
[21:13:25.457] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:25.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:72:0) after 1m0s
[21:14:25.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:72:0)
[21:14:25.481] Timer tick, asking Buildbucket for the build status
[21:14:25.551] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:25.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:73:0) after 1m0s
[21:15:25.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:73:0)
[21:15:25.779] Timer tick, asking Buildbucket for the build status
[21:15:25.994] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:25.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:74:0) after 1m0s
[21:16:26.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:74:0)
[21:16:26.021] Timer tick, asking Buildbucket for the build status
[21:16:26.355] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:26.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:75:0) after 1m0s
[21:17:26.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:75:0)
[21:17:26.402] Timer tick, asking Buildbucket for the build status
[21:17:26.449] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:26.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:76:0) after 1m0s
[21:18:26.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:76:0)
[21:18:26.429] Timer tick, asking Buildbucket for the build status
[21:18:26.578] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:26.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:77:0) after 1m0s
[21:19:26.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:77:0)
[21:19:26.646] Timer tick, asking Buildbucket for the build status
[21:19:26.800] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:26.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:78:0) after 1m0s
[21:20:26.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:78:0)
[21:20:26.839] Timer tick, asking Buildbucket for the build status
[21:20:27.426] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:27.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:79:0) after 1m0s
[21:21:27.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:79:0)
[21:21:27.408] Timer tick, asking Buildbucket for the build status
[21:21:27.708] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:27.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:80:0) after 1m0s
[21:22:27.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:80:0)
[21:22:27.725] Timer tick, asking Buildbucket for the build status
[21:22:27.795] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:27.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:81:0) after 1m0s
[21:23:27.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:81:0)
[21:23:27.818] Timer tick, asking Buildbucket for the build status
[21:23:27.870] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:27.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:82:0) after 1m0s
[21:24:27.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:82:0)
[21:24:27.859] Timer tick, asking Buildbucket for the build status
[21:24:27.956] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:27.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:83:0) after 1m0s
[21:25:27.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:83:0)
[21:25:27.948] Timer tick, asking Buildbucket for the build status
[21:25:28.020] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:28.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:84:0) after 1m0s
[21:26:28.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:84:0)
[21:26:28.042] Timer tick, asking Buildbucket for the build status
[21:26:28.178] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:28.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:85:0) after 1m0s
[21:27:28.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:85:0)
[21:27:28.252] Timer tick, asking Buildbucket for the build status
[21:27:28.500] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:28.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:86:0) after 1m0s
[21:28:28.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:86:0)
[21:28:28.534] Timer tick, asking Buildbucket for the build status
[21:28:28.585] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:28.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:87:0) after 1m0s
[21:29:28.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:87:0)
[21:29:28.598] Timer tick, asking Buildbucket for the build status
[21:29:28.767] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:28.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:88:0) after 1m0s
[21:30:28.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:88:0)
[21:30:28.805] Timer tick, asking Buildbucket for the build status
[21:30:28.900] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:28.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:89:0) after 1m0s
[21:31:28.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:89:0)
[21:31:28.887] Timer tick, asking Buildbucket for the build status
[21:31:29.928] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:29.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:90:0) after 1m0s
[21:32:29.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:90:0)
[21:32:29.944] Timer tick, asking Buildbucket for the build status
[21:32:30.107] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:30.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:91:0) after 1m0s
[21:33:30.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:91:0)
[21:33:30.154] Timer tick, asking Buildbucket for the build status
[21:33:30.379] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:30.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:92:0) after 1m0s
[21:34:30.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:92:0)
[21:34:30.379] Timer tick, asking Buildbucket for the build status
[21:34:30.494] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:30.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:93:0) after 1m0s
[21:35:30.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:93:0)
[21:35:30.496] Timer tick, asking Buildbucket for the build status
[21:35:30.558] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:30.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:94:0) after 1m0s
[21:36:30.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:94:0)
[21:36:30.609] Timer tick, asking Buildbucket for the build status
[21:36:30.728] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:30.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:95:0) after 1m0s
[21:37:30.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:95:0)
[21:37:30.731] Timer tick, asking Buildbucket for the build status
[21:37:31.237] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:31.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:96:0) after 1m0s
[21:38:31.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:96:0)
[21:38:31.623] Timer tick, asking Buildbucket for the build status
[21:38:31.680] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:31.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:97:0) after 1m0s
[21:39:31.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:97:0)
[21:39:31.676] Timer tick, asking Buildbucket for the build status
[21:39:31.840] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:31.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:98:0) after 1m0s
[21:40:31.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:98:0)
[21:40:31.864] Timer tick, asking Buildbucket for the build status
[21:40:32.293] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:32.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:99:0) after 1m0s
[21:41:32.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:99:0)
[21:41:32.318] Timer tick, asking Buildbucket for the build status
[21:41:32.410] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:32.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:100:0) after 1m0s
[21:42:32.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:100:0)
[21:42:32.432] Timer tick, asking Buildbucket for the build status
[21:42:32.491] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:32.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:101:0) after 1m0s
[21:43:32.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:101:0)
[21:43:32.539] Timer tick, asking Buildbucket for the build status
[21:43:32.610] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:32.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:102:0) after 1m0s
[21:44:32.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:102:0)
[21:44:32.633] Timer tick, asking Buildbucket for the build status
[21:44:32.773] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:32.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:103:0) after 1m0s
[21:45:32.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:103:0)
[21:45:32.798] Timer tick, asking Buildbucket for the build status
[21:45:33.203] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:33.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:104:0) after 1m0s
[21:46:33.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:104:0)
[21:46:33.208] Timer tick, asking Buildbucket for the build status
[21:46:33.263] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:33.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:105:0) after 1m0s
[21:47:33.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:105:0)
[21:47:33.298] Timer tick, asking Buildbucket for the build status
[21:47:33.341] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:33.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:106:0) after 1m0s
[21:48:33.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:106:0)
[21:48:33.378] Timer tick, asking Buildbucket for the build status
[21:48:33.486] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:33.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:107:0) after 1m0s
[21:49:33.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:107:0)
[21:49:33.515] Timer tick, asking Buildbucket for the build status
[21:49:33.606] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:33.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:108:0) after 1m0s
[21:50:33.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:108:0)
[21:50:33.662] Timer tick, asking Buildbucket for the build status
[21:50:33.760] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:33.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:109:0) after 1m0s
[21:51:33.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:109:0)
[21:51:33.791] Timer tick, asking Buildbucket for the build status
[21:51:34.033] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:34.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:110:0) after 1m0s
[21:52:34.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:110:0)
[21:52:34.015] Timer tick, asking Buildbucket for the build status
[21:52:34.565] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:34.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:111:0) after 1m0s
[21:53:34.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:111:0)
[21:53:34.590] Timer tick, asking Buildbucket for the build status
[21:53:34.631] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:34.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:112:0) after 1m0s
[21:54:34.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:112:0)
[21:54:34.727] Timer tick, asking Buildbucket for the build status
[21:54:34.775] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:34.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:113:0) after 1m0s
[21:55:35.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:113:0)
[21:55:35.006] Timer tick, asking Buildbucket for the build status
[21:55:35.111] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:35.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:114:0) after 1m0s
[21:56:35.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:114:0)
[21:56:35.331] Timer tick, asking Buildbucket for the build status
[21:56:35.536] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:35.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:115:0) after 1m0s
[21:57:35.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:115:0)
[21:57:35.556] Timer tick, asking Buildbucket for the build status
[21:57:35.606] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:35.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:116:0) after 1m0s
[21:58:35.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:116:0)
[21:58:35.614] Timer tick, asking Buildbucket for the build status
[21:58:35.744] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:35.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:117:0) after 1m0s
[21:59:35.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:117:0)
[21:59:35.771] Timer tick, asking Buildbucket for the build status
[21:59:36.006] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:36.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:118:0) after 1m0s
[22:00:36.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:118:0)
[22:00:36.072] Timer tick, asking Buildbucket for the build status
[22:00:36.208] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:36.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:119:0) after 1m0s
[22:01:36.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:119:0)
[22:01:36.234] Timer tick, asking Buildbucket for the build status
[22:01:36.287] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:36.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:120:0) after 1m0s
[22:02:36.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:120:0)
[22:02:36.309] Timer tick, asking Buildbucket for the build status
[22:02:36.405] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:36.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:121:0) after 1m0s
[22:03:36.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:121:0)
[22:03:36.439] Timer tick, asking Buildbucket for the build status
[22:03:36.537] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:36.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:122:0) after 1m0s
[22:04:36.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:122:0)
[22:04:36.538] Timer tick, asking Buildbucket for the build status
[22:04:36.753] Build 8913454660073883440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:36.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078884722557054592:123:0) after 1m0s
[22:04:54.465] Received PubSub notification, asking Buildbucket for the build status
[22:04:54.524] Build 8913454660073883440: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:04:54.524] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557871492726319",
"created_by": "project:fuchsia",
"created_ts": "1557864242443379",
"failure_reason": "BUILD_FAILURE",
"id": "8913454660073883440",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"dc2f5f9ff81dbc4f963adf023fadea3404d084e5\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$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}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-2-aa7e\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"dc2f5f9ff81dbc4f963adf023fadea3404d084e5\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-2-aa7e\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4297-bcf2400\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1557864253856817",
"status": "COMPLETED",
"status_changed_ts": "1557864254324463",
"tags": [
"builder:clang",
"buildset:commit/git/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/dc2f5f9ff81dbc4f963adf023fadea3404d084e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078884722557054592",
"scheduler_job_id:fuchsia/prod-clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8913454660073883440/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:44d0c36e34a30510",
"user_agent:luci-scheduler"
],
"updated_ts": "1557871493269390",
"url": "https://ci.chromium.org/b/8913454660073883440",
"utcnow_ts": "1557871494512531"
}
}
[22:04:54.526] Invocation finished in 2h0m53.832127717s with status FAILED