[02:19:02.396] New invocation is queued and will start shortly
[02:19:03.521] Starting the invocation (attempt 1)
[02:19:03.627] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:19:03.627] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:19:03.628] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079857694465601536",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"1e393064fa2732e721524f95a12348b0fd981167\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079857694465601536",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/1e393064fa2732e721524f95a12348b0fd981167",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e393064fa2732e721524f95a12348b0fd981167",
"gitiles_ref:refs/heads/master"
]
}
[02:19:04.620] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556936343803871",
"id": "8914427632318671328",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1e393064fa2732e721524f95a12348b0fd981167\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556936344352782",
"tags": [
"builder:clang",
"buildset:commit/git/1e393064fa2732e721524f95a12348b0fd981167",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e393064fa2732e721524f95a12348b0fd981167",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079857694465601536",
"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/8914427632318671328/+/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": "1556936344352915",
"url": "https://ci.chromium.org/b/8914427632318671328",
"utcnow_ts": "1556936344607895"
}
}
[02:19:04.620] Task URL: https://ci.chromium.org/b/8914427632318671328
[02:19:04.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:2:0) after 1m0s
[02:19:18.940] Received PubSub notification, asking Buildbucket for the build status
[02:19:19.053] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:04.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:2:0)
[02:20:04.641] Timer tick, asking Buildbucket for the build status
[02:20:04.740] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:04.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:4:0) after 1m0s
[02:21:04.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:4:0)
[02:21:04.760] Timer tick, asking Buildbucket for the build status
[02:21:05.244] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:05.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:5:0) after 1m0s
[02:22:05.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:5:0)
[02:22:05.261] Timer tick, asking Buildbucket for the build status
[02:22:05.315] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:05.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:6:0) after 1m0s
[02:23:05.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:6:0)
[02:23:05.343] Timer tick, asking Buildbucket for the build status
[02:23:05.419] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:05.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:7:0) after 1m0s
[02:24:05.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:7:0)
[02:24:05.414] Timer tick, asking Buildbucket for the build status
[02:24:05.457] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:05.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:8:0) after 1m0s
[02:25:05.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:8:0)
[02:25:05.442] Timer tick, asking Buildbucket for the build status
[02:25:05.636] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:05.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:9:0) after 1m0s
[02:26:05.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:9:0)
[02:26:05.656] Timer tick, asking Buildbucket for the build status
[02:26:06.063] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:06.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:10:0) after 1m0s
[02:27:06.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:10:0)
[02:27:06.089] Timer tick, asking Buildbucket for the build status
[02:27:06.532] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:06.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:11:0) after 1m0s
[02:28:06.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:11:0)
[02:28:06.550] Timer tick, asking Buildbucket for the build status
[02:28:06.607] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:06.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:12:0) after 1m0s
[02:29:06.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:12:0)
[02:29:06.692] Timer tick, asking Buildbucket for the build status
[02:29:06.823] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:06.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:13:0) after 1m0s
[02:30:06.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:13:0)
[02:30:06.845] Timer tick, asking Buildbucket for the build status
[02:30:07.210] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:07.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:14:0) after 1m0s
[02:31:07.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:14:0)
[02:31:07.228] Timer tick, asking Buildbucket for the build status
[02:31:07.285] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:07.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:15:0) after 1m0s
[02:32:07.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:15:0)
[02:32:07.304] Timer tick, asking Buildbucket for the build status
[02:32:07.517] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:07.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:16:0) after 1m0s
[02:33:07.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:16:0)
[02:33:07.536] Timer tick, asking Buildbucket for the build status
[02:33:07.599] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:07.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:17:0) after 1m0s
[02:34:07.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:17:0)
[02:34:07.579] Timer tick, asking Buildbucket for the build status
[02:34:07.658] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:07.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:18:0) after 1m0s
[02:35:07.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:18:0)
[02:35:07.678] Timer tick, asking Buildbucket for the build status
[02:35:07.751] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:07.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:19:0) after 1m0s
[02:36:07.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:19:0)
[02:36:07.770] Timer tick, asking Buildbucket for the build status
[02:36:07.838] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:07.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:20:0) after 1m0s
[02:37:07.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:20:0)
[02:37:07.857] Timer tick, asking Buildbucket for the build status
[02:37:08.013] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:08.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:21:0) after 1m0s
[02:38:08.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:21:0)
[02:38:08.035] Timer tick, asking Buildbucket for the build status
[02:38:08.255] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:08.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:22:0) after 1m0s
[02:39:08.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:22:0)
[02:39:08.228] Timer tick, asking Buildbucket for the build status
[02:39:08.307] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:08.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:23:0) after 1m0s
[02:40:08.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:23:0)
[02:40:08.327] Timer tick, asking Buildbucket for the build status
[02:40:08.583] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:08.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:24:0) after 1m0s
[02:41:08.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:24:0)
[02:41:08.602] Timer tick, asking Buildbucket for the build status
[02:41:08.775] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:08.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:25:0) after 1m0s
[02:42:08.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:25:0)
[02:42:08.794] Timer tick, asking Buildbucket for the build status
[02:42:08.972] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:08.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:26:0) after 1m0s
[02:43:09.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:26:0)
[02:43:09.020] Timer tick, asking Buildbucket for the build status
[02:43:10.004] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:10.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:27:0) after 1m0s
[02:44:10.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:27:0)
[02:44:10.024] Timer tick, asking Buildbucket for the build status
[02:44:10.175] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:10.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:28:0) after 1m0s
[02:45:10.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:28:0)
[02:45:10.194] Timer tick, asking Buildbucket for the build status
[02:45:10.386] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:10.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:29:0) after 1m0s
[02:46:10.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:29:0)
[02:46:10.393] Timer tick, asking Buildbucket for the build status
[02:46:10.800] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:10.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:30:0) after 1m0s
[02:47:10.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:30:0)
[02:47:10.822] Timer tick, asking Buildbucket for the build status
[02:47:10.985] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:10.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:31:0) after 1m0s
[02:48:11.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:31:0)
[02:48:11.036] Timer tick, asking Buildbucket for the build status
[02:48:12.050] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:12.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:32:0) after 1m0s
[02:49:12.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:32:0)
[02:49:12.115] Timer tick, asking Buildbucket for the build status
[02:49:12.208] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:12.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:33:0) after 1m0s
[02:50:12.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:33:0)
[02:50:12.215] Timer tick, asking Buildbucket for the build status
[02:50:12.274] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:12.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:34:0) after 1m0s
[02:51:12.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:34:0)
[02:51:12.261] Timer tick, asking Buildbucket for the build status
[02:51:13.144] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:13.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:35:0) after 1m0s
[02:52:13.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:35:0)
[02:52:13.190] Timer tick, asking Buildbucket for the build status
[02:52:13.506] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:13.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:36:0) after 1m0s
[02:53:13.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:36:0)
[02:53:13.580] Timer tick, asking Buildbucket for the build status
[02:53:14.001] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:14.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:37:0) after 1m0s
[02:54:14.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:37:0)
[02:54:14.035] Timer tick, asking Buildbucket for the build status
[02:54:14.110] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:14.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:38:0) after 1m0s
[02:55:14.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:38:0)
[02:55:14.131] Timer tick, asking Buildbucket for the build status
[02:55:14.200] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:14.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:39:0) after 1m0s
[02:56:14.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:39:0)
[02:56:14.240] Timer tick, asking Buildbucket for the build status
[02:56:14.601] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:14.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:40:0) after 1m0s
[02:57:14.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:40:0)
[02:57:14.620] Timer tick, asking Buildbucket for the build status
[02:57:15.607] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:15.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:41:0) after 1m0s
[02:58:15.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:41:0)
[02:58:15.624] Timer tick, asking Buildbucket for the build status
[02:58:15.691] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:15.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:42:0) after 1m0s
[02:59:15.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:42:0)
[02:59:15.707] Timer tick, asking Buildbucket for the build status
[02:59:15.860] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:15.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:43:0) after 1m0s
[03:00:15.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:43:0)
[03:00:15.849] Timer tick, asking Buildbucket for the build status
[03:00:16.014] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:16.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:44:0) after 1m0s
[03:01:16.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:44:0)
[03:01:16.064] Timer tick, asking Buildbucket for the build status
[03:01:16.298] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:16.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:45:0) after 1m0s
[03:02:16.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:45:0)
[03:02:16.317] Timer tick, asking Buildbucket for the build status
[03:02:16.380] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:16.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:46:0) after 1m0s
[03:03:16.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:46:0)
[03:03:16.372] Timer tick, asking Buildbucket for the build status
[03:03:16.414] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:16.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:47:0) after 1m0s
[03:04:16.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:47:0)
[03:04:16.437] Timer tick, asking Buildbucket for the build status
[03:04:16.638] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:16.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:48:0) after 1m0s
[03:05:16.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:48:0)
[03:05:16.676] Timer tick, asking Buildbucket for the build status
[03:05:16.733] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:16.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:49:0) after 1m0s
[03:06:16.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:49:0)
[03:06:16.751] Timer tick, asking Buildbucket for the build status
[03:06:16.991] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:16.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:50:0) after 1m0s
[03:07:17.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:50:0)
[03:07:17.011] Timer tick, asking Buildbucket for the build status
[03:07:17.205] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:17.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:51:0) after 1m0s
[03:08:17.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:51:0)
[03:08:17.194] Timer tick, asking Buildbucket for the build status
[03:08:17.235] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:17.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:52:0) after 1m0s
[03:09:17.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:52:0)
[03:09:17.265] Timer tick, asking Buildbucket for the build status
[03:09:17.650] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:17.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:53:0) after 1m0s
[03:10:17.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:53:0)
[03:10:17.674] Timer tick, asking Buildbucket for the build status
[03:10:17.741] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:17.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:54:0) after 1m0s
[03:11:17.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:54:0)
[03:11:17.763] Timer tick, asking Buildbucket for the build status
[03:11:17.889] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:17.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:55:0) after 1m0s
[03:12:17.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:55:0)
[03:12:17.911] Timer tick, asking Buildbucket for the build status
[03:12:18.066] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:18.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:56:0) after 1m0s
[03:13:18.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:56:0)
[03:13:18.092] Timer tick, asking Buildbucket for the build status
[03:13:18.271] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:18.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:57:0) after 1m0s
[03:14:18.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:57:0)
[03:14:18.297] Timer tick, asking Buildbucket for the build status
[03:14:18.368] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:58:0) after 1m0s
[03:15:18.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:58:0)
[03:15:18.384] Timer tick, asking Buildbucket for the build status
[03:15:18.656] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:18.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:59:0) after 1m0s
[03:16:18.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:59:0)
[03:16:18.669] Timer tick, asking Buildbucket for the build status
[03:16:18.736] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:18.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:60:0) after 1m0s
[03:17:18.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:60:0)
[03:17:18.757] Timer tick, asking Buildbucket for the build status
[03:17:18.846] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:18.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:61:0) after 1m0s
[03:18:18.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:61:0)
[03:18:18.900] Timer tick, asking Buildbucket for the build status
[03:18:18.962] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:18.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:62:0) after 1m0s
[03:19:18.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:62:0)
[03:19:18.972] Timer tick, asking Buildbucket for the build status
[03:19:19.061] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:19.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:63:0) after 1m0s
[03:20:19.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:63:0)
[03:20:19.085] Timer tick, asking Buildbucket for the build status
[03:20:19.215] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:19.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:64:0) after 1m0s
[03:21:19.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:64:0)
[03:21:19.238] Timer tick, asking Buildbucket for the build status
[03:21:19.417] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:19.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:65:0) after 1m0s
[03:22:19.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:65:0)
[03:22:19.446] Timer tick, asking Buildbucket for the build status
[03:22:19.700] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:19.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:66:0) after 1m0s
[03:23:19.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:66:0)
[03:23:19.703] Timer tick, asking Buildbucket for the build status
[03:23:20.393] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:20.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:67:0) after 1m0s
[03:24:20.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:67:0)
[03:24:20.418] Timer tick, asking Buildbucket for the build status
[03:24:20.503] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:20.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:68:0) after 1m0s
[03:25:20.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:68:0)
[03:25:20.519] Timer tick, asking Buildbucket for the build status
[03:25:20.577] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:20.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:69:0) after 1m0s
[03:26:20.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:69:0)
[03:26:20.597] Timer tick, asking Buildbucket for the build status
[03:26:20.641] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:20.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:70:0) after 1m0s
[03:27:20.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:70:0)
[03:27:20.661] Timer tick, asking Buildbucket for the build status
[03:27:20.738] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:20.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:71:0) after 1m0s
[03:28:20.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:71:0)
[03:28:20.759] Timer tick, asking Buildbucket for the build status
[03:28:20.923] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:20.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:72:0) after 1m0s
[03:29:20.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:72:0)
[03:29:20.946] Timer tick, asking Buildbucket for the build status
[03:29:21.021] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:21.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:73:0) after 1m0s
[03:30:21.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:73:0)
[03:30:21.047] Timer tick, asking Buildbucket for the build status
[03:30:21.388] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:21.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:74:0) after 1m0s
[03:31:21.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:74:0)
[03:31:21.423] Timer tick, asking Buildbucket for the build status
[03:31:21.571] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:21.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:75:0) after 1m0s
[03:32:21.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:75:0)
[03:32:21.556] Timer tick, asking Buildbucket for the build status
[03:32:21.748] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:21.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:76:0) after 1m0s
[03:33:21.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:76:0)
[03:33:21.749] Timer tick, asking Buildbucket for the build status
[03:33:21.821] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:21.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:77:0) after 1m0s
[03:34:21.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:77:0)
[03:34:21.828] Timer tick, asking Buildbucket for the build status
[03:34:21.910] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:21.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:78:0) after 1m0s
[03:35:21.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:78:0)
[03:35:21.899] Timer tick, asking Buildbucket for the build status
[03:35:22.019] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:22.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:79:0) after 1m0s
[03:36:22.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:79:0)
[03:36:22.037] Timer tick, asking Buildbucket for the build status
[03:36:22.096] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:22.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:80:0) after 1m0s
[03:37:22.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:80:0)
[03:37:22.065] Timer tick, asking Buildbucket for the build status
[03:37:22.174] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:81:0) after 1m0s
[03:38:22.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:81:0)
[03:38:22.198] Timer tick, asking Buildbucket for the build status
[03:38:22.389] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:22.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:82:0) after 1m0s
[03:39:22.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:82:0)
[03:39:22.368] Timer tick, asking Buildbucket for the build status
[03:39:22.444] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:22.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:83:0) after 1m0s
[03:40:22.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:83:0)
[03:40:22.466] Timer tick, asking Buildbucket for the build status
[03:40:23.821] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:23.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:84:0) after 1m0s
[03:41:23.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:84:0)
[03:41:23.857] Timer tick, asking Buildbucket for the build status
[03:41:24.381] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:24.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:85:0) after 1m0s
[03:42:24.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:85:0)
[03:42:24.543] Timer tick, asking Buildbucket for the build status
[03:42:24.646] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:24.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:86:0) after 1m0s
[03:43:24.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:86:0)
[03:43:24.642] Timer tick, asking Buildbucket for the build status
[03:43:24.789] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:24.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:87:0) after 1m0s
[03:44:24.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:87:0)
[03:44:24.780] Timer tick, asking Buildbucket for the build status
[03:44:25.132] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:25.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:88:0) after 1m0s
[03:45:25.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:88:0)
[03:45:25.154] Timer tick, asking Buildbucket for the build status
[03:45:25.223] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:25.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:89:0) after 1m0s
[03:46:25.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:89:0)
[03:46:25.238] Timer tick, asking Buildbucket for the build status
[03:46:25.296] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:90:0) after 1m0s
[03:47:25.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:90:0)
[03:47:25.316] Timer tick, asking Buildbucket for the build status
[03:47:25.362] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:25.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:91:0) after 1m0s
[03:48:25.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:91:0)
[03:48:25.368] Timer tick, asking Buildbucket for the build status
[03:48:25.424] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:25.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:92:0) after 1m0s
[03:49:25.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:92:0)
[03:49:25.446] Timer tick, asking Buildbucket for the build status
[03:49:25.669] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:25.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:93:0) after 1m0s
[03:50:25.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:93:0)
[03:50:25.688] Timer tick, asking Buildbucket for the build status
[03:50:25.905] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:25.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:94:0) after 1m0s
[03:51:25.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:94:0)
[03:51:25.942] Timer tick, asking Buildbucket for the build status
[03:51:25.985] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:25.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:95:0) after 1m0s
[03:52:26.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:95:0)
[03:52:26.021] Timer tick, asking Buildbucket for the build status
[03:52:26.445] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:26.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:96:0) after 1m0s
[03:53:26.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:96:0)
[03:53:26.466] Timer tick, asking Buildbucket for the build status
[03:53:26.633] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:26.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:97:0) after 1m0s
[03:54:26.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:97:0)
[03:54:26.656] Timer tick, asking Buildbucket for the build status
[03:54:26.730] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:26.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:98:0) after 1m0s
[03:55:26.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:98:0)
[03:55:26.752] Timer tick, asking Buildbucket for the build status
[03:55:26.986] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:26.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:99:0) after 1m0s
[03:56:27.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:99:0)
[03:56:27.007] Timer tick, asking Buildbucket for the build status
[03:56:27.059] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:27.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:100:0) after 1m0s
[03:57:27.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:100:0)
[03:57:27.094] Timer tick, asking Buildbucket for the build status
[03:57:27.199] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:27.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:101:0) after 1m0s
[03:58:27.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:101:0)
[03:58:27.194] Timer tick, asking Buildbucket for the build status
[03:58:27.342] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:27.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:102:0) after 1m0s
[03:59:27.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:102:0)
[03:59:27.358] Timer tick, asking Buildbucket for the build status
[03:59:27.641] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:27.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:103:0) after 1m0s
[04:00:27.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:103:0)
[04:00:27.688] Timer tick, asking Buildbucket for the build status
[04:00:27.797] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:27.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:104:0) after 1m0s
[04:01:27.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:104:0)
[04:01:27.818] Timer tick, asking Buildbucket for the build status
[04:01:27.892] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:27.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:105:0) after 1m0s
[04:02:27.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:105:0)
[04:02:27.914] Timer tick, asking Buildbucket for the build status
[04:02:27.988] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:27.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:106:0) after 1m0s
[04:03:28.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:106:0)
[04:03:28.062] Timer tick, asking Buildbucket for the build status
[04:03:28.186] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:28.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:107:0) after 1m0s
[04:04:28.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:107:0)
[04:04:28.211] Timer tick, asking Buildbucket for the build status
[04:04:28.267] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:28.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:108:0) after 1m0s
[04:05:28.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:108:0)
[04:05:28.295] Timer tick, asking Buildbucket for the build status
[04:05:28.560] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:28.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:109:0) after 1m0s
[04:06:28.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:109:0)
[04:06:28.579] Timer tick, asking Buildbucket for the build status
[04:06:28.658] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:28.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:110:0) after 1m0s
[04:07:28.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:110:0)
[04:07:28.682] Timer tick, asking Buildbucket for the build status
[04:07:28.732] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:28.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:111:0) after 1m0s
[04:08:28.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:111:0)
[04:08:28.752] Timer tick, asking Buildbucket for the build status
[04:08:28.847] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:28.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:112:0) after 1m0s
[04:09:28.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:112:0)
[04:09:28.903] Timer tick, asking Buildbucket for the build status
[04:09:28.976] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:28.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:113:0) after 1m0s
[04:10:29.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:113:0)
[04:10:29.013] Timer tick, asking Buildbucket for the build status
[04:10:29.234] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:114:0) after 1m0s
[04:11:29.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:114:0)
[04:11:29.252] Timer tick, asking Buildbucket for the build status
[04:11:29.311] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:29.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:115:0) after 1m0s
[04:12:29.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:115:0)
[04:12:29.358] Timer tick, asking Buildbucket for the build status
[04:12:29.412] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:29.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:116:0) after 1m0s
[04:13:29.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:116:0)
[04:13:29.405] Timer tick, asking Buildbucket for the build status
[04:13:29.457] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:29.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:117:0) after 1m0s
[04:14:29.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:117:0)
[04:14:29.476] Timer tick, asking Buildbucket for the build status
[04:14:29.542] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:118:0) after 1m0s
[04:15:29.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:118:0)
[04:15:29.580] Timer tick, asking Buildbucket for the build status
[04:15:29.694] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:119:0) after 1m0s
[04:16:29.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:119:0)
[04:16:29.719] Timer tick, asking Buildbucket for the build status
[04:16:29.785] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:29.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:120:0) after 1m0s
[04:17:29.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:120:0)
[04:17:29.820] Timer tick, asking Buildbucket for the build status
[04:17:29.862] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:121:0) after 1m0s
[04:18:29.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:121:0)
[04:18:29.968] Timer tick, asking Buildbucket for the build status
[04:18:30.442] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:30.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:122:0) after 1m0s
[04:19:30.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:122:0)
[04:19:30.464] Timer tick, asking Buildbucket for the build status
[04:19:30.758] Build 8914427632318671328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:30.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079857694465601536:123:0) after 1m0s
[04:19:43.412] Received PubSub notification, asking Buildbucket for the build status
[04:19:43.508] Build 8914427632318671328: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:19:43.508] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556943582791551",
"created_by": "project:fuchsia",
"created_ts": "1556936343803871",
"failure_reason": "BUILD_FAILURE",
"id": "8914427632318671328",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1e393064fa2732e721524f95a12348b0fd981167\"}}",
"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-debian-1-003729e9-us-central1-c-sd4j\", \"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\": \"1e393064fa2732e721524f95a12348b0fd981167\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-003729e9-us-central1-c-sd4j\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190426-c90e1d5-prod\"], \"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\": [\"4284-fd5e65b\"], \"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": "1556936356559674",
"status": "COMPLETED",
"status_changed_ts": "1556936358692616",
"tags": [
"builder:clang",
"buildset:commit/git/1e393064fa2732e721524f95a12348b0fd981167",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e393064fa2732e721524f95a12348b0fd981167",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079857694465601536",
"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/8914427632318671328/+/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:449974cceb6ae910",
"user_agent:luci-scheduler"
],
"updated_ts": "1556943582983599",
"url": "https://ci.chromium.org/b/8914427632318671328",
"utcnow_ts": "1556943583497631"
}
}
[04:19:43.508] Invocation finished in 2h0m41.135719268s with status FAILED