[03:31:46.154] New invocation is queued and will start shortly
[03:31:47.298] Starting the invocation (attempt 1)
[03:31:47.356] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:31:47.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:31:47.357] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079037746029083408",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a263aa25e1f8851bc0e1ff7d391e0d9abd860454\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079037746029083408",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"gitiles_ref:refs/heads/master"
]
}
[03:31:48.323] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557718307493434",
"id": "8913607683960387392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a263aa25e1f8851bc0e1ff7d391e0d9abd860454\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557718307939956",
"tags": [
"builder:clang",
"buildset:commit/git/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079037746029083408",
"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/8913607683960387392/+/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": "1557718307940091",
"url": "https://ci.chromium.org/b/8913607683960387392",
"utcnow_ts": "1557718308306541"
}
}
[03:31:48.324] Task URL: https://ci.chromium.org/b/8913607683960387392
[03:31:48.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:2:0) after 1m0s
[03:31:53.592] Received PubSub notification, asking Buildbucket for the build status
[03:31:53.657] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:48.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:2:0)
[03:32:48.351] Timer tick, asking Buildbucket for the build status
[03:32:48.486] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:48.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:4:0) after 1m0s
[03:33:48.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:4:0)
[03:33:48.490] Timer tick, asking Buildbucket for the build status
[03:33:48.709] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:48.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:5:0) after 1m0s
[03:34:48.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:5:0)
[03:34:48.784] Timer tick, asking Buildbucket for the build status
[03:34:48.847] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:48.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:6:0) after 1m0s
[03:35:48.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:6:0)
[03:35:48.853] Timer tick, asking Buildbucket for the build status
[03:35:48.972] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:48.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:7:0) after 1m0s
[03:36:48.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:7:0)
[03:36:48.983] Timer tick, asking Buildbucket for the build status
[03:36:49.067] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:49.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:8:0) after 1m0s
[03:37:49.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:8:0)
[03:37:49.090] Timer tick, asking Buildbucket for the build status
[03:37:49.269] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:49.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:9:0) after 1m0s
[03:38:49.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:9:0)
[03:38:49.286] Timer tick, asking Buildbucket for the build status
[03:38:49.506] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:49.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:10:0) after 1m0s
[03:39:49.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:10:0)
[03:39:49.587] Timer tick, asking Buildbucket for the build status
[03:39:49.665] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:49.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:11:0) after 1m0s
[03:40:49.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:11:0)
[03:40:49.689] Timer tick, asking Buildbucket for the build status
[03:40:49.899] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:49.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:12:0) after 1m0s
[03:41:49.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:12:0)
[03:41:49.927] Timer tick, asking Buildbucket for the build status
[03:41:52.010] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:52.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:13:0) after 1m0s
[03:42:52.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:13:0)
[03:42:52.049] Timer tick, asking Buildbucket for the build status
[03:42:52.119] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:52.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:14:0) after 1m0s
[03:43:52.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:14:0)
[03:43:52.136] Timer tick, asking Buildbucket for the build status
[03:43:52.205] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:52.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:15:0) after 1m0s
[03:44:52.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:15:0)
[03:44:52.227] Timer tick, asking Buildbucket for the build status
[03:44:53.248] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:53.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:16:0) after 1m0s
[03:45:53.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:16:0)
[03:45:53.276] Timer tick, asking Buildbucket for the build status
[03:45:53.436] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:53.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:17:0) after 1m0s
[03:46:53.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:17:0)
[03:46:53.478] Timer tick, asking Buildbucket for the build status
[03:46:53.556] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:18:0) after 1m0s
[03:47:53.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:18:0)
[03:47:53.599] Timer tick, asking Buildbucket for the build status
[03:47:53.780] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:19:0) after 1m0s
[03:48:53.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:19:0)
[03:48:53.812] Timer tick, asking Buildbucket for the build status
[03:48:53.973] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:53.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:20:0) after 1m0s
[03:49:54.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:20:0)
[03:49:54.247] Timer tick, asking Buildbucket for the build status
[03:49:54.414] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:54.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:21:0) after 1m0s
[03:50:54.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:21:0)
[03:50:54.447] Timer tick, asking Buildbucket for the build status
[03:50:54.519] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:54.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:22:0) after 1m0s
[03:51:54.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:22:0)
[03:51:54.651] Timer tick, asking Buildbucket for the build status
[03:51:54.939] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:54.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:23:0) after 1m0s
[03:52:54.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:23:0)
[03:52:54.941] Timer tick, asking Buildbucket for the build status
[03:52:55.060] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:55.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:24:0) after 1m0s
[03:53:55.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:24:0)
[03:53:55.089] Timer tick, asking Buildbucket for the build status
[03:53:55.219] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:55.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:25:0) after 1m0s
[03:54:55.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:25:0)
[03:54:55.251] Timer tick, asking Buildbucket for the build status
[03:54:55.458] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:55.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:26:0) after 1m0s
[03:55:55.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:26:0)
[03:55:55.497] Timer tick, asking Buildbucket for the build status
[03:55:55.573] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:55.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:27:0) after 1m0s
[03:56:55.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:27:0)
[03:56:55.628] Timer tick, asking Buildbucket for the build status
[03:56:55.899] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:55.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:28:0) after 1m0s
[03:57:55.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:28:0)
[03:57:55.922] Timer tick, asking Buildbucket for the build status
[03:57:55.974] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:55.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:29:0) after 1m0s
[03:58:56.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:29:0)
[03:58:56.000] Timer tick, asking Buildbucket for the build status
[03:58:56.065] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:56.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:30:0) after 1m0s
[03:59:56.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:30:0)
[03:59:56.086] Timer tick, asking Buildbucket for the build status
[03:59:56.234] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:56.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:31:0) after 1m0s
[04:00:56.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:31:0)
[04:00:56.277] Timer tick, asking Buildbucket for the build status
[04:00:56.442] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:56.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:32:0) after 1m0s
[04:01:56.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:32:0)
[04:01:56.427] Timer tick, asking Buildbucket for the build status
[04:01:56.559] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:56.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:33:0) after 1m0s
[04:02:56.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:33:0)
[04:02:56.598] Timer tick, asking Buildbucket for the build status
[04:02:56.664] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:56.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:34:0) after 1m0s
[04:03:56.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:34:0)
[04:03:56.656] Timer tick, asking Buildbucket for the build status
[04:03:56.888] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:56.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:35:0) after 1m0s
[04:04:56.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:35:0)
[04:04:56.914] Timer tick, asking Buildbucket for the build status
[04:04:57.093] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:57.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:36:0) after 1m0s
[04:05:57.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:36:0)
[04:05:57.125] Timer tick, asking Buildbucket for the build status
[04:05:57.489] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:57.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:37:0) after 1m0s
[04:07:47.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:37:0)
[04:07:47.999] Timer tick, asking Buildbucket for the build status
[04:07:48.131] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:48.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:38:0) after 1m0s
[04:08:48.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:38:0)
[04:08:48.308] Timer tick, asking Buildbucket for the build status
[04:08:48.628] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:48.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:39:0) after 1m0s
[04:09:48.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:39:0)
[04:09:48.648] Timer tick, asking Buildbucket for the build status
[04:09:48.723] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:48.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:40:0) after 1m0s
[04:10:48.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:40:0)
[04:10:48.744] Timer tick, asking Buildbucket for the build status
[04:10:48.800] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:48.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:41:0) after 1m0s
[04:11:48.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:41:0)
[04:11:48.799] Timer tick, asking Buildbucket for the build status
[04:11:48.881] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:48.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:42:0) after 1m0s
[04:12:48.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:42:0)
[04:12:48.889] Timer tick, asking Buildbucket for the build status
[04:12:49.071] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:49.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:43:0) after 1m0s
[04:13:49.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:43:0)
[04:13:49.093] Timer tick, asking Buildbucket for the build status
[04:13:49.205] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:49.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:44:0) after 1m0s
[04:14:49.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:44:0)
[04:14:49.207] Timer tick, asking Buildbucket for the build status
[04:14:49.276] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:49.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:45:0) after 1m0s
[04:15:49.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:45:0)
[04:15:49.918] Timer tick, asking Buildbucket for the build status
[04:15:50.306] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:50.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:46:0) after 1m0s
[04:16:56.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:46:0)
[04:16:56.441] Timer tick, asking Buildbucket for the build status
[04:16:56.528] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:56.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:47:0) after 1m0s
[04:17:56.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:47:0)
[04:17:56.557] Timer tick, asking Buildbucket for the build status
[04:17:56.837] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:56.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:48:0) after 1m0s
[04:18:56.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:48:0)
[04:18:56.950] Timer tick, asking Buildbucket for the build status
[04:18:57.064] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:57.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:49:0) after 1m0s
[04:19:57.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:49:0)
[04:19:57.085] Timer tick, asking Buildbucket for the build status
[04:19:58.115] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:58.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:50:0) after 1m0s
[04:20:58.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:50:0)
[04:20:58.118] Timer tick, asking Buildbucket for the build status
[04:20:58.659] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:58.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:51:0) after 1m0s
[04:21:58.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:51:0)
[04:21:58.720] Timer tick, asking Buildbucket for the build status
[04:21:58.881] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:58.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:52:0) after 1m0s
[04:22:58.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:52:0)
[04:22:58.901] Timer tick, asking Buildbucket for the build status
[04:22:59.014] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:59.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:53:0) after 1m0s
[04:24:05.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:53:0)
[04:24:05.624] Timer tick, asking Buildbucket for the build status
[04:24:05.749] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:05.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:54:0) after 1m0s
[04:25:06.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:54:0)
[04:25:06.216] Timer tick, asking Buildbucket for the build status
[04:25:06.438] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:06.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:55:0) after 1m0s
[04:26:09.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:55:0)
[04:26:09.543] Timer tick, asking Buildbucket for the build status
[04:26:09.675] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:09.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:56:0) after 1m0s
[04:27:10.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:56:0)
[04:27:10.296] Timer tick, asking Buildbucket for the build status
[04:27:10.494] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:10.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:57:0) after 1m0s
[04:28:10.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:57:0)
[04:28:10.505] Timer tick, asking Buildbucket for the build status
[04:28:10.684] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:10.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:58:0) after 1m0s
[04:29:11.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:58:0)
[04:29:11.730] Timer tick, asking Buildbucket for the build status
[04:29:11.901] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:11.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:59:0) after 1m0s
[04:30:11.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:59:0)
[04:30:11.971] Timer tick, asking Buildbucket for the build status
[04:30:12.181] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:12.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:60:0) after 1m0s
[04:31:12.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:60:0)
[04:31:12.248] Timer tick, asking Buildbucket for the build status
[04:31:12.313] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:12.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:61:0) after 1m0s
[04:32:12.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:61:0)
[04:32:12.350] Timer tick, asking Buildbucket for the build status
[04:32:12.631] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:12.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:62:0) after 1m0s
[04:33:14.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:62:0)
[04:33:14.270] Timer tick, asking Buildbucket for the build status
[04:33:14.378] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:14.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:63:0) after 1m0s
[04:34:14.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:63:0)
[04:34:14.549] Timer tick, asking Buildbucket for the build status
[04:34:14.652] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:14.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:64:0) after 1m0s
[04:35:14.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:64:0)
[04:35:14.643] Timer tick, asking Buildbucket for the build status
[04:35:15.145] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:15.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:65:0) after 1m0s
[04:36:15.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:65:0)
[04:36:15.177] Timer tick, asking Buildbucket for the build status
[04:36:15.276] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:15.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:66:0) after 1m0s
[04:37:15.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:66:0)
[04:37:15.319] Timer tick, asking Buildbucket for the build status
[04:37:15.420] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:15.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:67:0) after 1m0s
[04:38:15.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:67:0)
[04:38:15.451] Timer tick, asking Buildbucket for the build status
[04:38:15.520] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:15.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:68:0) after 1m0s
[04:39:15.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:68:0)
[04:39:15.504] Timer tick, asking Buildbucket for the build status
[04:39:15.571] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:15.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:69:0) after 1m0s
[04:40:15.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:69:0)
[04:40:15.579] Timer tick, asking Buildbucket for the build status
[04:40:15.681] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:15.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:70:0) after 1m0s
[04:41:15.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:70:0)
[04:41:15.703] Timer tick, asking Buildbucket for the build status
[04:41:15.845] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:15.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:71:0) after 1m0s
[04:42:15.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:71:0)
[04:42:15.849] Timer tick, asking Buildbucket for the build status
[04:42:15.970] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:15.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:72:0) after 1m0s
[04:43:16.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:72:0)
[04:43:16.641] Timer tick, asking Buildbucket for the build status
[04:43:16.691] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:16.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:73:0) after 1m0s
[04:44:16.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:73:0)
[04:44:16.717] Timer tick, asking Buildbucket for the build status
[04:44:16.933] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:16.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:74:0) after 1m0s
[04:45:16.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:74:0)
[04:45:16.973] Timer tick, asking Buildbucket for the build status
[04:45:17.101] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:17.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:75:0) after 1m0s
[04:46:17.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:75:0)
[04:46:17.085] Timer tick, asking Buildbucket for the build status
[04:46:17.817] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:17.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:76:0) after 1m0s
[04:47:17.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:76:0)
[04:47:17.838] Timer tick, asking Buildbucket for the build status
[04:47:17.987] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:17.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:77:0) after 1m0s
[04:48:18.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:77:0)
[04:48:18.017] Timer tick, asking Buildbucket for the build status
[04:48:18.276] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:18.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:78:0) after 1m0s
[04:49:18.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:78:0)
[04:49:18.301] Timer tick, asking Buildbucket for the build status
[04:49:18.401] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:18.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:79:0) after 1m0s
[04:50:18.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:79:0)
[04:50:18.422] Timer tick, asking Buildbucket for the build status
[04:50:18.484] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:18.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:80:0) after 1m0s
[04:51:18.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:80:0)
[04:51:18.513] Timer tick, asking Buildbucket for the build status
[04:51:18.596] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:18.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:81:0) after 1m0s
[04:52:18.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:81:0)
[04:52:18.572] Timer tick, asking Buildbucket for the build status
[04:52:18.715] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:18.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:82:0) after 1m0s
[04:53:18.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:82:0)
[04:53:18.797] Timer tick, asking Buildbucket for the build status
[04:53:18.880] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:18.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:83:0) after 1m0s
[04:54:19.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:83:0)
[04:54:19.048] Timer tick, asking Buildbucket for the build status
[04:54:19.177] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:19.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:84:0) after 1m0s
[04:55:19.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:84:0)
[04:55:19.199] Timer tick, asking Buildbucket for the build status
[04:55:19.305] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:19.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:85:0) after 1m0s
[04:56:19.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:85:0)
[04:56:19.499] Timer tick, asking Buildbucket for the build status
[04:56:19.608] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:19.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:86:0) after 1m0s
[04:57:19.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:86:0)
[04:57:19.626] Timer tick, asking Buildbucket for the build status
[04:57:19.785] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:19.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:87:0) after 1m0s
[04:58:19.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:87:0)
[04:58:19.818] Timer tick, asking Buildbucket for the build status
[04:58:19.908] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:19.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:88:0) after 1m0s
[04:59:19.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:88:0)
[04:59:19.943] Timer tick, asking Buildbucket for the build status
[04:59:20.011] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:20.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:89:0) after 1m0s
[05:00:20.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:89:0)
[05:00:20.048] Timer tick, asking Buildbucket for the build status
[05:00:20.099] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:20.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:90:0) after 1m0s
[05:01:20.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:90:0)
[05:01:20.123] Timer tick, asking Buildbucket for the build status
[05:01:20.202] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:20.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:91:0) after 1m0s
[05:02:20.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:91:0)
[05:02:20.248] Timer tick, asking Buildbucket for the build status
[05:02:20.319] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:20.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:92:0) after 1m0s
[05:03:20.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:92:0)
[05:03:20.340] Timer tick, asking Buildbucket for the build status
[05:03:20.459] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:20.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:93:0) after 1m0s
[05:04:20.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:93:0)
[05:04:20.487] Timer tick, asking Buildbucket for the build status
[05:04:20.637] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:20.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:94:0) after 1m0s
[05:05:20.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:94:0)
[05:05:20.668] Timer tick, asking Buildbucket for the build status
[05:05:20.773] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:20.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:95:0) after 1m0s
[05:06:20.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:95:0)
[05:06:20.795] Timer tick, asking Buildbucket for the build status
[05:06:20.864] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:20.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:96:0) after 1m0s
[05:07:20.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:96:0)
[05:07:20.882] Timer tick, asking Buildbucket for the build status
[05:07:21.074] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:21.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:97:0) after 1m0s
[05:08:21.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:97:0)
[05:08:21.139] Timer tick, asking Buildbucket for the build status
[05:08:21.352] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:21.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:98:0) after 1m0s
[05:09:21.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:98:0)
[05:09:21.377] Timer tick, asking Buildbucket for the build status
[05:09:21.461] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:21.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:99:0) after 1m0s
[05:10:21.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:99:0)
[05:10:21.450] Timer tick, asking Buildbucket for the build status
[05:10:21.561] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:21.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:100:0) after 1m0s
[05:11:21.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:100:0)
[05:11:21.588] Timer tick, asking Buildbucket for the build status
[05:11:21.975] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:21.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:101:0) after 1m0s
[05:12:21.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:101:0)
[05:12:21.990] Timer tick, asking Buildbucket for the build status
[05:12:22.065] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:22.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:102:0) after 1m0s
[05:13:22.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:102:0)
[05:13:22.087] Timer tick, asking Buildbucket for the build status
[05:13:22.376] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:22.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:103:0) after 1m0s
[05:14:22.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:103:0)
[05:14:22.361] Timer tick, asking Buildbucket for the build status
[05:14:22.551] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:22.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:104:0) after 1m0s
[05:15:22.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:104:0)
[05:15:22.569] Timer tick, asking Buildbucket for the build status
[05:15:23.307] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:23.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:105:0) after 1m0s
[05:16:23.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:105:0)
[05:16:23.314] Timer tick, asking Buildbucket for the build status
[05:16:23.362] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:23.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:106:0) after 1m0s
[05:17:23.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:106:0)
[05:17:23.382] Timer tick, asking Buildbucket for the build status
[05:17:23.655] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:23.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:107:0) after 1m0s
[05:18:23.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:107:0)
[05:18:23.677] Timer tick, asking Buildbucket for the build status
[05:18:23.745] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:23.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:108:0) after 1m0s
[05:19:23.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:108:0)
[05:19:23.783] Timer tick, asking Buildbucket for the build status
[05:19:23.906] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:23.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:109:0) after 1m0s
[05:20:23.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:109:0)
[05:20:23.930] Timer tick, asking Buildbucket for the build status
[05:20:24.501] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:24.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:110:0) after 1m0s
[05:21:24.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:110:0)
[05:21:24.492] Timer tick, asking Buildbucket for the build status
[05:21:24.628] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:24.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:111:0) after 1m0s
[05:22:24.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:111:0)
[05:22:24.654] Timer tick, asking Buildbucket for the build status
[05:22:24.795] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:24.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:112:0) after 1m0s
[05:23:24.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:112:0)
[05:23:24.841] Timer tick, asking Buildbucket for the build status
[05:23:24.967] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:24.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:113:0) after 1m0s
[05:24:24.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:113:0)
[05:24:24.993] Timer tick, asking Buildbucket for the build status
[05:24:25.099] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:25.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:114:0) after 1m0s
[05:25:25.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:114:0)
[05:25:25.182] Timer tick, asking Buildbucket for the build status
[05:25:25.261] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:25.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:115:0) after 1m0s
[05:26:25.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:115:0)
[05:26:25.251] Timer tick, asking Buildbucket for the build status
[05:26:25.336] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:25.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:116:0) after 1m0s
[05:27:25.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:116:0)
[05:27:25.375] Timer tick, asking Buildbucket for the build status
[05:27:25.539] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:25.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:117:0) after 1m0s
[05:28:25.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:117:0)
[05:28:25.562] Timer tick, asking Buildbucket for the build status
[05:28:25.648] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:25.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:118:0) after 1m0s
[05:29:43.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:118:0)
[05:29:43.437] Timer tick, asking Buildbucket for the build status
[05:29:43.515] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:43.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:119:0) after 1m0s
[05:30:43.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:119:0)
[05:30:43.531] Timer tick, asking Buildbucket for the build status
[05:30:43.851] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:43.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:120:0) after 1m0s
[05:31:44.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:120:0)
[05:31:44.030] Timer tick, asking Buildbucket for the build status
[05:31:44.092] Build 8913607683960387392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:44.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079037746029083408:121:0) after 1m0s
[05:32:29.699] Received PubSub notification, asking Buildbucket for the build status
[05:32:29.744] Build 8913607683960387392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:32:29.744] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557725549321449",
"created_by": "project:fuchsia",
"created_ts": "1557718307493434",
"failure_reason": "BUILD_FAILURE",
"id": "8913607683960387392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a263aa25e1f8851bc0e1ff7d391e0d9abd860454\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-0-sf9i\", \"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\": \"a263aa25e1f8851bc0e1ff7d391e0d9abd860454\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-0-sf9i\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4292-d8c05fa\"], \"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": "1557718312392404",
"status": "COMPLETED",
"status_changed_ts": "1557718313003220",
"tags": [
"builder:clang",
"buildset:commit/git/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a263aa25e1f8851bc0e1ff7d391e0d9abd860454",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079037746029083408",
"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/8913607683960387392/+/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:44c8109eab302010",
"user_agent:luci-scheduler"
],
"updated_ts": "1557725549462552",
"url": "https://ci.chromium.org/b/8913607683960387392",
"utcnow_ts": "1557725549737970"
}
}
[05:32:29.744] Invocation finished in 2h0m43.607178062s with status FAILED