[06:08:56.132] New invocation is queued and will start shortly
[06:08:57.203] Starting the invocation (attempt 1)
[06:08:57.232] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:08:57.233] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:08:57.233] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081926960905168096",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"7cc03bd06487313575c82264abf018098060f795\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081926960905168096",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/7cc03bd06487313575c82264abf018098060f795",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7cc03bd06487313575c82264abf018098060f795",
"gitiles_ref:refs/heads/master"
]
}
[06:08:57.831] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554962937335272",
"id": "8916496898979456896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7cc03bd06487313575c82264abf018098060f795\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554962937702575",
"tags": [
"builder:clang",
"buildset:commit/git/7cc03bd06487313575c82264abf018098060f795",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7cc03bd06487313575c82264abf018098060f795",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081926960905168096",
"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/8916496898979456896/+/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": "1554962937702701",
"url": "https://ci.chromium.org/b/8916496898979456896",
"utcnow_ts": "1554962937821144"
}
}
[06:08:57.831] Task URL: https://ci.chromium.org/b/8916496898979456896
[06:08:57.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:2:0) after 1m0s
[06:09:25.908] Received PubSub notification, asking Buildbucket for the build status
[06:09:25.965] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:2:0)
[06:09:57.820] Timer tick, asking Buildbucket for the build status
[06:09:57.901] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:4:0) after 1m0s
[06:10:57.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:4:0)
[06:10:57.902] Timer tick, asking Buildbucket for the build status
[06:10:58.021] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:58.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:5:0) after 1m0s
[06:11:58.040] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:5:0)
[06:11:58.040] Timer tick, asking Buildbucket for the build status
[06:11:58.104] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:58.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:6:0) after 1m0s
[06:12:58.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:6:0)
[06:12:58.124] Timer tick, asking Buildbucket for the build status
[06:12:58.280] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:58.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:7:0) after 1m0s
[06:13:58.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:7:0)
[06:13:58.300] Timer tick, asking Buildbucket for the build status
[06:13:58.404] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:58.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:8:0) after 1m0s
[06:14:58.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:8:0)
[06:14:58.421] Timer tick, asking Buildbucket for the build status
[06:14:58.482] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:58.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:9:0) after 1m0s
[06:15:58.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:9:0)
[06:15:58.499] Timer tick, asking Buildbucket for the build status
[06:15:58.543] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:58.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:10:0) after 1m0s
[06:16:58.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:10:0)
[06:16:58.559] Timer tick, asking Buildbucket for the build status
[06:16:58.734] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:58.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:11:0) after 1m0s
[06:17:58.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:11:0)
[06:17:58.751] Timer tick, asking Buildbucket for the build status
[06:17:59.016] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:59.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:12:0) after 1m0s
[06:18:59.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:12:0)
[06:18:59.036] Timer tick, asking Buildbucket for the build status
[06:18:59.147] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:59.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:13:0) after 1m0s
[06:19:59.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:13:0)
[06:19:59.136] Timer tick, asking Buildbucket for the build status
[06:19:59.277] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:59.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:14:0) after 1m0s
[06:20:59.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:14:0)
[06:20:59.247] Timer tick, asking Buildbucket for the build status
[06:20:59.671] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:59.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:15:0) after 1m0s
[06:21:59.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:15:0)
[06:21:59.696] Timer tick, asking Buildbucket for the build status
[06:21:59.769] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:59.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:16:0) after 1m0s
[06:22:59.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:16:0)
[06:22:59.788] Timer tick, asking Buildbucket for the build status
[06:23:00.272] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:00.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:17:0) after 1m0s
[06:24:00.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:17:0)
[06:24:00.291] Timer tick, asking Buildbucket for the build status
[06:24:00.354] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:00.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:18:0) after 1m0s
[06:25:00.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:18:0)
[06:25:00.389] Timer tick, asking Buildbucket for the build status
[06:25:00.521] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:00.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:19:0) after 1m0s
[06:26:00.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:19:0)
[06:26:00.513] Timer tick, asking Buildbucket for the build status
[06:26:00.688] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:00.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:20:0) after 1m0s
[06:27:00.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:20:0)
[06:27:00.701] Timer tick, asking Buildbucket for the build status
[06:27:00.792] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:00.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:21:0) after 1m0s
[06:28:00.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:21:0)
[06:28:00.815] Timer tick, asking Buildbucket for the build status
[06:28:00.897] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:00.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:22:0) after 1m0s
[06:29:00.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:22:0)
[06:29:00.924] Timer tick, asking Buildbucket for the build status
[06:29:01.032] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:01.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:23:0) after 1m0s
[06:30:01.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:23:0)
[06:30:01.060] Timer tick, asking Buildbucket for the build status
[06:30:01.373] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:01.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:24:0) after 1m0s
[06:31:01.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:24:0)
[06:31:01.392] Timer tick, asking Buildbucket for the build status
[06:31:01.659] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:01.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:25:0) after 1m0s
[06:32:01.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:25:0)
[06:32:01.677] Timer tick, asking Buildbucket for the build status
[06:32:01.741] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:01.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:26:0) after 1m0s
[06:33:01.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:26:0)
[06:33:01.716] Timer tick, asking Buildbucket for the build status
[06:33:01.961] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:01.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:27:0) after 1m0s
[06:34:01.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:27:0)
[06:34:01.963] Timer tick, asking Buildbucket for the build status
[06:34:02.037] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:02.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:28:0) after 1m0s
[06:35:02.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:28:0)
[06:35:02.059] Timer tick, asking Buildbucket for the build status
[06:35:02.253] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:02.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:29:0) after 1m0s
[06:36:02.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:29:0)
[06:36:02.297] Timer tick, asking Buildbucket for the build status
[06:36:02.453] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:02.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:30:0) after 1m0s
[06:37:02.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:30:0)
[06:37:02.446] Timer tick, asking Buildbucket for the build status
[06:37:02.708] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:02.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:31:0) after 1m0s
[06:38:02.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:31:0)
[06:38:02.725] Timer tick, asking Buildbucket for the build status
[06:38:02.807] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:02.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:32:0) after 1m0s
[06:39:02.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:32:0)
[06:39:02.830] Timer tick, asking Buildbucket for the build status
[06:39:03.248] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:33:0) after 1m0s
[06:40:03.244] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:33:0)
[06:40:03.244] Timer tick, asking Buildbucket for the build status
[06:40:03.310] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:03.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:34:0) after 1m0s
[06:41:03.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:34:0)
[06:41:03.330] Timer tick, asking Buildbucket for the build status
[06:41:03.394] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:03.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:35:0) after 1m0s
[06:42:03.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:35:0)
[06:42:03.416] Timer tick, asking Buildbucket for the build status
[06:42:05.413] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:05.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:36:0) after 1m0s
[06:43:05.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:36:0)
[06:43:05.441] Timer tick, asking Buildbucket for the build status
[06:43:05.519] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:05.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:37:0) after 1m0s
[06:44:05.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:37:0)
[06:44:05.516] Timer tick, asking Buildbucket for the build status
[06:44:05.588] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:05.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:38:0) after 1m0s
[06:45:05.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:38:0)
[06:45:05.579] Timer tick, asking Buildbucket for the build status
[06:45:05.686] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:05.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:39:0) after 1m0s
[06:46:05.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:39:0)
[06:46:05.712] Timer tick, asking Buildbucket for the build status
[06:46:05.868] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:05.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:40:0) after 1m0s
[06:47:05.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:40:0)
[06:47:05.897] Timer tick, asking Buildbucket for the build status
[06:47:06.524] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:06.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:41:0) after 1m0s
[06:48:06.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:41:0)
[06:48:06.545] Timer tick, asking Buildbucket for the build status
[06:48:06.610] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:42:0) after 1m0s
[06:49:06.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:42:0)
[06:49:06.608] Timer tick, asking Buildbucket for the build status
[06:49:06.698] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:06.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:43:0) after 1m0s
[06:50:06.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:43:0)
[06:50:06.722] Timer tick, asking Buildbucket for the build status
[06:50:06.800] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:06.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:44:0) after 1m0s
[06:51:06.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:44:0)
[06:51:06.824] Timer tick, asking Buildbucket for the build status
[06:51:06.877] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:06.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:45:0) after 1m0s
[06:52:06.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:45:0)
[06:52:06.896] Timer tick, asking Buildbucket for the build status
[06:52:06.938] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:06.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:46:0) after 1m0s
[06:53:06.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:46:0)
[06:53:06.964] Timer tick, asking Buildbucket for the build status
[06:53:07.038] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:07.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:47:0) after 1m0s
[06:54:07.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:47:0)
[06:54:07.059] Timer tick, asking Buildbucket for the build status
[06:54:07.097] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:07.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:48:0) after 1m0s
[06:55:07.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:48:0)
[06:55:07.128] Timer tick, asking Buildbucket for the build status
[06:55:07.189] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:07.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:49:0) after 1m0s
[06:56:07.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:49:0)
[06:56:07.184] Timer tick, asking Buildbucket for the build status
[06:56:07.311] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:07.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:50:0) after 1m0s
[06:57:07.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:50:0)
[06:57:07.347] Timer tick, asking Buildbucket for the build status
[06:57:07.675] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:07.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:51:0) after 1m0s
[06:58:07.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:51:0)
[06:58:07.687] Timer tick, asking Buildbucket for the build status
[06:58:07.815] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:07.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:52:0) after 1m0s
[06:59:07.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:52:0)
[06:59:07.819] Timer tick, asking Buildbucket for the build status
[06:59:07.917] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:07.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:53:0) after 1m0s
[07:00:07.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:53:0)
[07:00:07.948] Timer tick, asking Buildbucket for the build status
[07:00:08.035] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:08.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:54:0) after 1m0s
[07:01:08.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:54:0)
[07:01:08.071] Timer tick, asking Buildbucket for the build status
[07:01:08.126] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:08.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:55:0) after 1m0s
[07:02:08.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:55:0)
[07:02:08.147] Timer tick, asking Buildbucket for the build status
[07:02:08.230] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:08.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:56:0) after 1m0s
[07:03:08.219] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:56:0)
[07:03:08.219] Timer tick, asking Buildbucket for the build status
[07:03:08.296] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:08.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:57:0) after 1m0s
[07:04:08.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:57:0)
[07:04:08.318] Timer tick, asking Buildbucket for the build status
[07:04:08.410] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:08.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:58:0) after 1m0s
[07:05:08.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:58:0)
[07:05:08.422] Timer tick, asking Buildbucket for the build status
[07:05:08.491] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:08.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:59:0) after 1m0s
[07:06:08.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:59:0)
[07:06:08.514] Timer tick, asking Buildbucket for the build status
[07:06:08.711] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:08.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:60:0) after 1m0s
[07:07:08.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:60:0)
[07:07:08.753] Timer tick, asking Buildbucket for the build status
[07:07:08.865] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:08.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:61:0) after 1m0s
[07:08:08.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:61:0)
[07:08:08.895] Timer tick, asking Buildbucket for the build status
[07:08:08.971] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:08.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:62:0) after 1m0s
[07:09:08.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:62:0)
[07:09:08.992] Timer tick, asking Buildbucket for the build status
[07:09:09.129] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:09.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:63:0) after 1m0s
[07:10:09.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:63:0)
[07:10:09.164] Timer tick, asking Buildbucket for the build status
[07:10:09.364] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:09.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:64:0) after 1m0s
[07:11:09.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:64:0)
[07:11:09.384] Timer tick, asking Buildbucket for the build status
[07:11:09.427] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:09.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:65:0) after 1m0s
[07:12:09.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:65:0)
[07:12:09.416] Timer tick, asking Buildbucket for the build status
[07:12:09.556] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:09.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:66:0) after 1m0s
[07:13:09.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:66:0)
[07:13:09.547] Timer tick, asking Buildbucket for the build status
[07:13:09.692] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:09.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:67:0) after 1m0s
[07:14:09.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:67:0)
[07:14:09.704] Timer tick, asking Buildbucket for the build status
[07:14:09.848] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:09.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:68:0) after 1m0s
[07:15:09.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:68:0)
[07:15:09.872] Timer tick, asking Buildbucket for the build status
[07:15:09.928] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:09.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:69:0) after 1m0s
[07:16:09.952] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:69:0)
[07:16:09.952] Timer tick, asking Buildbucket for the build status
[07:16:10.062] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:10.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:70:0) after 1m0s
[07:17:10.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:70:0)
[07:17:10.085] Timer tick, asking Buildbucket for the build status
[07:17:10.181] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:10.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:71:0) after 1m0s
[07:18:10.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:71:0)
[07:18:10.205] Timer tick, asking Buildbucket for the build status
[07:18:10.337] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:10.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:72:0) after 1m0s
[07:19:10.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:72:0)
[07:19:10.374] Timer tick, asking Buildbucket for the build status
[07:19:10.427] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:10.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:73:0) after 1m0s
[07:20:10.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:73:0)
[07:20:10.451] Timer tick, asking Buildbucket for the build status
[07:20:10.529] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:10.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:74:0) after 1m0s
[07:21:10.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:74:0)
[07:21:10.507] Timer tick, asking Buildbucket for the build status
[07:21:10.595] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:10.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:75:0) after 1m0s
[07:22:10.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:75:0)
[07:22:10.614] Timer tick, asking Buildbucket for the build status
[07:22:10.679] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:10.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:76:0) after 1m0s
[07:23:10.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:76:0)
[07:23:10.673] Timer tick, asking Buildbucket for the build status
[07:23:10.735] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:10.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:77:0) after 1m0s
[07:24:10.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:77:0)
[07:24:10.760] Timer tick, asking Buildbucket for the build status
[07:24:11.004] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:11.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:78:0) after 1m0s
[07:25:11.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:78:0)
[07:25:11.027] Timer tick, asking Buildbucket for the build status
[07:25:11.139] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:11.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:79:0) after 1m0s
[07:26:11.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:79:0)
[07:26:11.166] Timer tick, asking Buildbucket for the build status
[07:26:11.255] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:11.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:80:0) after 1m0s
[07:27:11.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:80:0)
[07:27:11.281] Timer tick, asking Buildbucket for the build status
[07:27:11.393] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:11.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:81:0) after 1m0s
[07:28:11.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:81:0)
[07:28:11.417] Timer tick, asking Buildbucket for the build status
[07:28:11.492] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:11.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:82:0) after 1m0s
[07:29:11.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:82:0)
[07:29:11.516] Timer tick, asking Buildbucket for the build status
[07:29:11.647] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:11.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:83:0) after 1m0s
[07:30:11.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:83:0)
[07:30:11.671] Timer tick, asking Buildbucket for the build status
[07:30:11.823] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:11.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:84:0) after 1m0s
[07:31:11.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:84:0)
[07:31:11.830] Timer tick, asking Buildbucket for the build status
[07:31:12.138] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:12.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:85:0) after 1m0s
[07:32:12.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:85:0)
[07:32:12.160] Timer tick, asking Buildbucket for the build status
[07:32:12.388] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:12.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:86:0) after 1m0s
[07:33:12.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:86:0)
[07:33:12.413] Timer tick, asking Buildbucket for the build status
[07:33:12.619] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:12.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:87:0) after 1m0s
[07:34:12.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:87:0)
[07:34:12.640] Timer tick, asking Buildbucket for the build status
[07:34:12.682] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:12.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:88:0) after 1m0s
[07:35:12.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:88:0)
[07:35:12.704] Timer tick, asking Buildbucket for the build status
[07:35:12.781] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:89:0) after 1m0s
[07:36:12.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:89:0)
[07:36:12.802] Timer tick, asking Buildbucket for the build status
[07:36:12.846] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:12.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:90:0) after 1m0s
[07:37:12.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:90:0)
[07:37:12.876] Timer tick, asking Buildbucket for the build status
[07:37:12.932] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:12.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:91:0) after 1m0s
[07:38:12.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:91:0)
[07:38:12.942] Timer tick, asking Buildbucket for the build status
[07:38:13.044] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:13.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:92:0) after 1m0s
[07:39:13.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:92:0)
[07:39:13.083] Timer tick, asking Buildbucket for the build status
[07:39:13.142] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:13.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:93:0) after 1m0s
[07:40:13.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:93:0)
[07:40:13.164] Timer tick, asking Buildbucket for the build status
[07:40:13.258] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:13.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:94:0) after 1m0s
[07:41:13.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:94:0)
[07:41:13.280] Timer tick, asking Buildbucket for the build status
[07:41:13.495] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:13.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:95:0) after 1m0s
[07:42:13.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:95:0)
[07:42:13.515] Timer tick, asking Buildbucket for the build status
[07:42:13.586] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:13.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:96:0) after 1m0s
[07:43:13.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:96:0)
[07:43:13.642] Timer tick, asking Buildbucket for the build status
[07:43:13.781] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:13.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:97:0) after 1m0s
[07:44:13.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:97:0)
[07:44:13.801] Timer tick, asking Buildbucket for the build status
[07:44:13.865] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:13.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:98:0) after 1m0s
[07:45:13.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:98:0)
[07:45:13.841] Timer tick, asking Buildbucket for the build status
[07:45:13.908] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:13.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:99:0) after 1m0s
[07:46:13.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:99:0)
[07:46:13.930] Timer tick, asking Buildbucket for the build status
[07:46:14.124] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:14.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:100:0) after 1m0s
[07:47:14.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:100:0)
[07:47:14.144] Timer tick, asking Buildbucket for the build status
[07:47:14.424] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:14.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:101:0) after 1m0s
[07:48:14.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:101:0)
[07:48:14.421] Timer tick, asking Buildbucket for the build status
[07:48:14.682] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:14.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:102:0) after 1m0s
[07:49:14.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:102:0)
[07:49:14.699] Timer tick, asking Buildbucket for the build status
[07:49:14.805] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:14.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:103:0) after 1m0s
[07:50:14.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:103:0)
[07:50:14.813] Timer tick, asking Buildbucket for the build status
[07:50:14.918] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:14.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:104:0) after 1m0s
[07:51:14.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:104:0)
[07:51:14.899] Timer tick, asking Buildbucket for the build status
[07:51:14.970] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:14.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:105:0) after 1m0s
[07:52:14.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:105:0)
[07:52:14.987] Timer tick, asking Buildbucket for the build status
[07:52:15.196] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:15.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:106:0) after 1m0s
[07:53:15.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:106:0)
[07:53:15.194] Timer tick, asking Buildbucket for the build status
[07:53:15.252] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:15.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:107:0) after 1m0s
[07:54:15.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:107:0)
[07:54:15.273] Timer tick, asking Buildbucket for the build status
[07:54:15.366] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:15.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:108:0) after 1m0s
[07:55:15.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:108:0)
[07:55:15.375] Timer tick, asking Buildbucket for the build status
[07:55:15.437] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:15.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:109:0) after 1m0s
[07:56:15.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:109:0)
[07:56:15.425] Timer tick, asking Buildbucket for the build status
[07:56:15.483] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:15.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:110:0) after 1m0s
[07:57:15.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:110:0)
[07:57:15.508] Timer tick, asking Buildbucket for the build status
[07:57:15.579] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:15.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:111:0) after 1m0s
[07:58:15.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:111:0)
[07:58:15.599] Timer tick, asking Buildbucket for the build status
[07:58:15.669] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:15.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:112:0) after 1m0s
[07:59:15.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:112:0)
[07:59:15.680] Timer tick, asking Buildbucket for the build status
[07:59:15.784] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:15.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:113:0) after 1m0s
[08:00:15.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:113:0)
[08:00:15.806] Timer tick, asking Buildbucket for the build status
[08:00:15.877] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:15.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:114:0) after 1m0s
[08:01:15.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:114:0)
[08:01:15.904] Timer tick, asking Buildbucket for the build status
[08:01:15.989] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:15.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:115:0) after 1m0s
[08:02:16.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:115:0)
[08:02:16.013] Timer tick, asking Buildbucket for the build status
[08:02:16.114] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:16.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:116:0) after 1m0s
[08:03:16.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:116:0)
[08:03:16.133] Timer tick, asking Buildbucket for the build status
[08:03:16.176] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:16.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:117:0) after 1m0s
[08:04:16.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:117:0)
[08:04:16.201] Timer tick, asking Buildbucket for the build status
[08:04:16.297] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:16.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:118:0) after 1m0s
[08:05:16.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:118:0)
[08:05:16.334] Timer tick, asking Buildbucket for the build status
[08:05:16.738] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:16.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:119:0) after 1m0s
[08:06:16.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:119:0)
[08:06:16.726] Timer tick, asking Buildbucket for the build status
[08:06:17.066] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:17.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:120:0) after 1m0s
[08:07:17.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:120:0)
[08:07:17.086] Timer tick, asking Buildbucket for the build status
[08:07:17.203] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:17.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:121:0) after 1m0s
[08:08:17.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:121:0)
[08:08:17.236] Timer tick, asking Buildbucket for the build status
[08:08:17.656] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:17.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:122:0) after 1m0s
[08:09:17.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:122:0)
[08:09:17.734] Timer tick, asking Buildbucket for the build status
[08:09:17.791] Build 8916496898979456896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:17.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081926960905168096:123:0) after 1m0s
[08:09:46.777] Received PubSub notification, asking Buildbucket for the build status
[08:09:46.833] Build 8916496898979456896: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:09:46.833] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554970186475228",
"created_by": "project:fuchsia",
"created_ts": "1554962937335272",
"failure_reason": "BUILD_FAILURE",
"id": "8916496898979456896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7cc03bd06487313575c82264abf018098060f795\"}}",
"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-ffce87c5-us-central1-b-cx2m\", \"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\": \"7cc03bd06487313575c82264abf018098060f795\", \"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-ffce87c5-us-central1-b-cx2m\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"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\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1554962965099232",
"status": "COMPLETED",
"status_changed_ts": "1554962965499355",
"tags": [
"builder:clang",
"buildset:commit/git/7cc03bd06487313575c82264abf018098060f795",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7cc03bd06487313575c82264abf018098060f795",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081926960905168096",
"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/8916496898979456896/+/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:4423d5017b786310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554970186533312",
"url": "https://ci.chromium.org/b/8916496898979456896",
"utcnow_ts": "1554970186824857"
}
}
[08:09:46.833] Invocation finished in 2h0m50.718682641s with status FAILED