[09:03:40.286] New invocation is queued and will start shortly
[09:03:41.426] Starting the invocation (attempt 1)
[09:03:41.540] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:03:41.540] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:03:41.540] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080557012976447808",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0eadd988662533f5ef89a2024dc7ebf830f16b67\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080557012976447808",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"gitiles_ref:refs/heads/master"
]
}
[09:03:42.212] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556269421675342",
"id": "8915126950856440656",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0eadd988662533f5ef89a2024dc7ebf830f16b67\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556269422007861",
"tags": [
"builder:clang",
"buildset:commit/git/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080557012976447808",
"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/8915126950856440656/+/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": "1556269422007966",
"url": "https://ci.chromium.org/b/8915126950856440656",
"utcnow_ts": "1556269422204794"
}
}
[09:03:42.212] Task URL: https://ci.chromium.org/b/8915126950856440656
[09:03:42.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:2:0) after 1m0s
[09:04:05.481] Received PubSub notification, asking Buildbucket for the build status
[09:04:06.473] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:42.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:2:0)
[09:04:42.241] Timer tick, asking Buildbucket for the build status
[09:04:42.321] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:42.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:4:0) after 1m0s
[09:05:42.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:4:0)
[09:05:42.295] Timer tick, asking Buildbucket for the build status
[09:05:42.419] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:42.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:5:0) after 1m0s
[09:06:42.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:5:0)
[09:06:42.442] Timer tick, asking Buildbucket for the build status
[09:06:42.491] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:42.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:6:0) after 1m0s
[09:07:42.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:6:0)
[09:07:42.482] Timer tick, asking Buildbucket for the build status
[09:07:42.543] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:42.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:7:0) after 1m0s
[09:08:42.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:7:0)
[09:08:42.564] Timer tick, asking Buildbucket for the build status
[09:08:42.680] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:42.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:8:0) after 1m0s
[09:09:42.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:8:0)
[09:09:42.686] Timer tick, asking Buildbucket for the build status
[09:09:42.772] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:42.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:9:0) after 1m0s
[09:10:42.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:9:0)
[09:10:42.790] Timer tick, asking Buildbucket for the build status
[09:10:42.981] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:42.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:10:0) after 1m0s
[09:11:43.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:10:0)
[09:11:43.042] Timer tick, asking Buildbucket for the build status
[09:11:43.186] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:43.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:11:0) after 1m0s
[09:12:43.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:11:0)
[09:12:43.207] Timer tick, asking Buildbucket for the build status
[09:12:43.364] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:43.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:12:0) after 1m0s
[09:13:43.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:12:0)
[09:13:43.395] Timer tick, asking Buildbucket for the build status
[09:13:43.438] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:43.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:13:0) after 1m0s
[09:14:43.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:13:0)
[09:14:43.456] Timer tick, asking Buildbucket for the build status
[09:14:43.551] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:43.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:14:0) after 1m0s
[09:15:43.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:14:0)
[09:15:43.553] Timer tick, asking Buildbucket for the build status
[09:15:43.768] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:43.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:15:0) after 1m0s
[09:16:43.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:15:0)
[09:16:43.789] Timer tick, asking Buildbucket for the build status
[09:16:43.974] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:43.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:16:0) after 1m0s
[09:17:43.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:16:0)
[09:17:43.983] Timer tick, asking Buildbucket for the build status
[09:17:44.094] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:44.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:17:0) after 1m0s
[09:18:44.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:17:0)
[09:18:44.114] Timer tick, asking Buildbucket for the build status
[09:18:44.185] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:44.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:18:0) after 1m0s
[09:19:44.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:18:0)
[09:19:44.168] Timer tick, asking Buildbucket for the build status
[09:19:44.225] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:44.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:19:0) after 1m0s
[09:20:44.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:19:0)
[09:20:44.215] Timer tick, asking Buildbucket for the build status
[09:20:44.265] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:44.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:20:0) after 1m0s
[09:21:44.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:20:0)
[09:21:44.293] Timer tick, asking Buildbucket for the build status
[09:21:44.392] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:44.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:21:0) after 1m0s
[09:22:44.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:21:0)
[09:22:44.414] Timer tick, asking Buildbucket for the build status
[09:22:44.636] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:44.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:22:0) after 1m0s
[09:23:44.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:22:0)
[09:23:44.657] Timer tick, asking Buildbucket for the build status
[09:23:44.731] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:44.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:23:0) after 1m0s
[09:24:44.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:23:0)
[09:24:44.739] Timer tick, asking Buildbucket for the build status
[09:24:44.785] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:44.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:24:0) after 1m0s
[09:25:44.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:24:0)
[09:25:44.756] Timer tick, asking Buildbucket for the build status
[09:25:44.877] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:44.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:25:0) after 1m0s
[09:26:44.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:25:0)
[09:26:44.897] Timer tick, asking Buildbucket for the build status
[09:26:45.102] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:45.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:26:0) after 1m0s
[09:27:45.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:26:0)
[09:27:45.125] Timer tick, asking Buildbucket for the build status
[09:27:45.183] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:45.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:27:0) after 1m0s
[09:28:45.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:27:0)
[09:28:45.216] Timer tick, asking Buildbucket for the build status
[09:28:45.258] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:45.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:28:0) after 1m0s
[09:29:45.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:28:0)
[09:29:45.296] Timer tick, asking Buildbucket for the build status
[09:29:45.358] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:29:0) after 1m0s
[09:30:45.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:29:0)
[09:30:45.378] Timer tick, asking Buildbucket for the build status
[09:30:45.436] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:45.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:30:0) after 1m0s
[09:31:45.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:30:0)
[09:31:45.438] Timer tick, asking Buildbucket for the build status
[09:31:45.556] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:45.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:31:0) after 1m0s
[09:32:45.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:31:0)
[09:32:45.584] Timer tick, asking Buildbucket for the build status
[09:32:45.647] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:45.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:32:0) after 1m0s
[09:33:45.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:32:0)
[09:33:45.666] Timer tick, asking Buildbucket for the build status
[09:33:45.781] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:45.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:33:0) after 1m0s
[09:34:45.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:33:0)
[09:34:45.790] Timer tick, asking Buildbucket for the build status
[09:34:45.830] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:45.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:34:0) after 1m0s
[09:35:45.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:34:0)
[09:35:45.862] Timer tick, asking Buildbucket for the build status
[09:35:45.913] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:45.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:35:0) after 1m0s
[09:36:45.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:35:0)
[09:36:45.951] Timer tick, asking Buildbucket for the build status
[09:36:46.019] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:46.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:36:0) after 1m0s
[09:37:46.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:36:0)
[09:37:46.123] Timer tick, asking Buildbucket for the build status
[09:37:46.163] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:46.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:37:0) after 1m0s
[09:38:46.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:37:0)
[09:38:46.211] Timer tick, asking Buildbucket for the build status
[09:38:46.276] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:46.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:38:0) after 1m0s
[09:39:46.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:38:0)
[09:39:46.302] Timer tick, asking Buildbucket for the build status
[09:39:46.373] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:46.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:39:0) after 1m0s
[09:40:46.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:39:0)
[09:40:46.395] Timer tick, asking Buildbucket for the build status
[09:40:46.656] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:46.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:40:0) after 1m0s
[09:41:46.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:40:0)
[09:41:46.677] Timer tick, asking Buildbucket for the build status
[09:41:46.736] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:46.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:41:0) after 1m0s
[09:42:46.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:41:0)
[09:42:46.738] Timer tick, asking Buildbucket for the build status
[09:42:46.829] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:46.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:42:0) after 1m0s
[09:43:46.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:42:0)
[09:43:46.858] Timer tick, asking Buildbucket for the build status
[09:43:46.985] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:46.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:43:0) after 1m0s
[09:44:47.010] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:43:0)
[09:44:47.010] Timer tick, asking Buildbucket for the build status
[09:44:47.078] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:47.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:44:0) after 1m0s
[09:45:47.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:44:0)
[09:45:47.096] Timer tick, asking Buildbucket for the build status
[09:45:47.186] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:47.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:45:0) after 1m0s
[09:46:47.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:45:0)
[09:46:47.207] Timer tick, asking Buildbucket for the build status
[09:46:47.362] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:47.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:46:0) after 1m0s
[09:47:47.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:46:0)
[09:47:47.387] Timer tick, asking Buildbucket for the build status
[09:47:47.469] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:47.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:47:0) after 1m0s
[09:48:47.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:47:0)
[09:48:47.476] Timer tick, asking Buildbucket for the build status
[09:48:47.531] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:47.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:48:0) after 1m0s
[09:49:47.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:48:0)
[09:49:47.515] Timer tick, asking Buildbucket for the build status
[09:49:47.613] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:49:0) after 1m0s
[09:50:47.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:49:0)
[09:50:47.642] Timer tick, asking Buildbucket for the build status
[09:50:47.761] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:47.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:50:0) after 1m0s
[09:51:47.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:50:0)
[09:51:47.792] Timer tick, asking Buildbucket for the build status
[09:51:47.867] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:47.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:51:0) after 1m0s
[09:52:47.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:51:0)
[09:52:47.905] Timer tick, asking Buildbucket for the build status
[09:52:47.951] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:47.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:52:0) after 1m0s
[09:53:47.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:52:0)
[09:53:47.975] Timer tick, asking Buildbucket for the build status
[09:53:48.083] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:53:0) after 1m0s
[09:54:48.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:53:0)
[09:54:48.106] Timer tick, asking Buildbucket for the build status
[09:54:48.210] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:48.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:54:0) after 1m0s
[09:55:48.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:54:0)
[09:55:48.237] Timer tick, asking Buildbucket for the build status
[09:55:48.284] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:48.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:55:0) after 1m0s
[09:56:48.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:55:0)
[09:56:48.277] Timer tick, asking Buildbucket for the build status
[09:56:48.337] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:48.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:56:0) after 1m0s
[09:57:48.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:56:0)
[09:57:48.355] Timer tick, asking Buildbucket for the build status
[09:57:48.427] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:48.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:57:0) after 1m0s
[09:58:48.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:57:0)
[09:58:48.413] Timer tick, asking Buildbucket for the build status
[09:58:48.483] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:48.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:58:0) after 1m0s
[09:59:48.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:58:0)
[09:59:48.477] Timer tick, asking Buildbucket for the build status
[09:59:48.532] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:48.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:59:0) after 1m0s
[10:00:48.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:59:0)
[10:00:48.558] Timer tick, asking Buildbucket for the build status
[10:00:48.755] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:48.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:60:0) after 1m0s
[10:01:48.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:60:0)
[10:01:48.777] Timer tick, asking Buildbucket for the build status
[10:01:48.835] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:48.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:61:0) after 1m0s
[10:02:48.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:61:0)
[10:02:48.857] Timer tick, asking Buildbucket for the build status
[10:02:48.947] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:48.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:62:0) after 1m0s
[10:03:48.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:62:0)
[10:03:48.977] Timer tick, asking Buildbucket for the build status
[10:03:49.042] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:63:0) after 1m0s
[10:04:49.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:63:0)
[10:04:49.044] Timer tick, asking Buildbucket for the build status
[10:04:49.096] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:64:0) after 1m0s
[10:05:49.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:64:0)
[10:05:49.090] Timer tick, asking Buildbucket for the build status
[10:05:49.164] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:49.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:65:0) after 1m0s
[10:06:49.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:65:0)
[10:06:49.164] Timer tick, asking Buildbucket for the build status
[10:06:49.255] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:49.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:66:0) after 1m0s
[10:07:49.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:66:0)
[10:07:49.251] Timer tick, asking Buildbucket for the build status
[10:07:49.310] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:49.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:67:0) after 1m0s
[10:08:49.335] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:67:0)
[10:08:49.335] Timer tick, asking Buildbucket for the build status
[10:08:49.375] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:49.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:68:0) after 1m0s
[10:09:49.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:68:0)
[10:09:49.393] Timer tick, asking Buildbucket for the build status
[10:09:49.484] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:49.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:69:0) after 1m0s
[10:10:49.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:69:0)
[10:10:49.504] Timer tick, asking Buildbucket for the build status
[10:10:49.573] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:49.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:70:0) after 1m0s
[10:11:49.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:70:0)
[10:11:49.595] Timer tick, asking Buildbucket for the build status
[10:11:49.729] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:49.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:71:0) after 1m0s
[10:12:49.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:71:0)
[10:12:49.748] Timer tick, asking Buildbucket for the build status
[10:12:49.822] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:49.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:72:0) after 1m0s
[10:13:49.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:72:0)
[10:13:49.850] Timer tick, asking Buildbucket for the build status
[10:13:50.001] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:50.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:73:0) after 1m0s
[10:14:50.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:73:0)
[10:14:50.034] Timer tick, asking Buildbucket for the build status
[10:14:50.083] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:50.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:74:0) after 1m0s
[10:15:50.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:74:0)
[10:15:50.104] Timer tick, asking Buildbucket for the build status
[10:15:50.228] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:50.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:75:0) after 1m0s
[10:16:50.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:75:0)
[10:16:50.256] Timer tick, asking Buildbucket for the build status
[10:16:50.489] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:50.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:76:0) after 1m0s
[10:17:50.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:76:0)
[10:17:50.526] Timer tick, asking Buildbucket for the build status
[10:17:50.607] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:50.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:77:0) after 1m0s
[10:18:50.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:77:0)
[10:18:50.627] Timer tick, asking Buildbucket for the build status
[10:18:50.712] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:50.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:78:0) after 1m0s
[10:19:50.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:78:0)
[10:19:50.730] Timer tick, asking Buildbucket for the build status
[10:19:50.853] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:50.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:79:0) after 1m0s
[10:20:50.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:79:0)
[10:20:50.874] Timer tick, asking Buildbucket for the build status
[10:20:50.935] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:50.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:80:0) after 1m0s
[10:21:50.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:80:0)
[10:21:50.915] Timer tick, asking Buildbucket for the build status
[10:21:50.988] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:50.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:81:0) after 1m0s
[10:22:51.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:81:0)
[10:22:51.015] Timer tick, asking Buildbucket for the build status
[10:22:51.156] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:51.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:82:0) after 1m0s
[10:23:51.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:82:0)
[10:23:51.166] Timer tick, asking Buildbucket for the build status
[10:23:51.233] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:51.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:83:0) after 1m0s
[10:24:51.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:83:0)
[10:24:51.254] Timer tick, asking Buildbucket for the build status
[10:24:51.377] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:51.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:84:0) after 1m0s
[10:25:51.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:84:0)
[10:25:51.365] Timer tick, asking Buildbucket for the build status
[10:25:51.455] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:51.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:85:0) after 1m0s
[10:26:51.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:85:0)
[10:26:51.477] Timer tick, asking Buildbucket for the build status
[10:26:51.628] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:51.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:86:0) after 1m0s
[10:27:51.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:86:0)
[10:27:51.627] Timer tick, asking Buildbucket for the build status
[10:27:51.788] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:51.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:87:0) after 1m0s
[10:28:51.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:87:0)
[10:28:51.762] Timer tick, asking Buildbucket for the build status
[10:28:51.835] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:51.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:88:0) after 1m0s
[10:29:51.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:88:0)
[10:29:51.845] Timer tick, asking Buildbucket for the build status
[10:29:51.914] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:51.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:89:0) after 1m0s
[10:30:51.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:89:0)
[10:30:51.937] Timer tick, asking Buildbucket for the build status
[10:30:52.301] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:52.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:90:0) after 1m0s
[10:31:52.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:90:0)
[10:31:52.338] Timer tick, asking Buildbucket for the build status
[10:31:52.418] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:52.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:91:0) after 1m0s
[10:32:52.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:91:0)
[10:32:52.434] Timer tick, asking Buildbucket for the build status
[10:32:52.495] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:52.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:92:0) after 1m0s
[10:33:52.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:92:0)
[10:33:52.516] Timer tick, asking Buildbucket for the build status
[10:33:52.572] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:52.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:93:0) after 1m0s
[10:34:52.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:93:0)
[10:34:52.593] Timer tick, asking Buildbucket for the build status
[10:34:52.743] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:52.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:94:0) after 1m0s
[10:35:52.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:94:0)
[10:35:52.766] Timer tick, asking Buildbucket for the build status
[10:35:52.852] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:52.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:95:0) after 1m0s
[10:36:52.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:95:0)
[10:36:52.854] Timer tick, asking Buildbucket for the build status
[10:36:53.026] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:53.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:96:0) after 1m0s
[10:37:53.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:96:0)
[10:37:53.022] Timer tick, asking Buildbucket for the build status
[10:37:53.710] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:53.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:97:0) after 1m0s
[10:38:53.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:97:0)
[10:38:53.750] Timer tick, asking Buildbucket for the build status
[10:38:54.132] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:54.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:98:0) after 1m0s
[10:39:54.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:98:0)
[10:39:54.480] Timer tick, asking Buildbucket for the build status
[10:39:54.541] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:54.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:99:0) after 1m0s
[10:40:54.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:99:0)
[10:40:54.761] Timer tick, asking Buildbucket for the build status
[10:40:55.045] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:55.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:100:0) after 1m0s
[10:41:55.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:100:0)
[10:41:55.092] Timer tick, asking Buildbucket for the build status
[10:41:55.167] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:55.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:101:0) after 1m0s
[10:42:55.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:101:0)
[10:42:55.188] Timer tick, asking Buildbucket for the build status
[10:42:55.394] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:55.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:102:0) after 1m0s
[10:43:55.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:102:0)
[10:43:55.396] Timer tick, asking Buildbucket for the build status
[10:43:55.594] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:55.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:103:0) after 1m0s
[10:44:55.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:103:0)
[10:44:55.614] Timer tick, asking Buildbucket for the build status
[10:44:55.655] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:55.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:104:0) after 1m0s
[10:45:55.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:104:0)
[10:45:55.684] Timer tick, asking Buildbucket for the build status
[10:45:55.751] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:55.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:105:0) after 1m0s
[10:46:55.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:105:0)
[10:46:55.772] Timer tick, asking Buildbucket for the build status
[10:46:55.939] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:55.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:106:0) after 1m0s
[10:47:55.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:106:0)
[10:47:55.961] Timer tick, asking Buildbucket for the build status
[10:47:56.024] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:56.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:107:0) after 1m0s
[10:48:56.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:107:0)
[10:48:56.049] Timer tick, asking Buildbucket for the build status
[10:48:56.140] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:56.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:108:0) after 1m0s
[10:49:56.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:108:0)
[10:49:56.131] Timer tick, asking Buildbucket for the build status
[10:49:56.279] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:56.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:109:0) after 1m0s
[10:50:56.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:109:0)
[10:50:56.303] Timer tick, asking Buildbucket for the build status
[10:50:56.561] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:56.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:110:0) after 1m0s
[10:51:56.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:110:0)
[10:51:56.584] Timer tick, asking Buildbucket for the build status
[10:51:56.629] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:56.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:111:0) after 1m0s
[10:52:56.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:111:0)
[10:52:56.649] Timer tick, asking Buildbucket for the build status
[10:52:56.741] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:56.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:112:0) after 1m0s
[10:53:56.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:112:0)
[10:53:56.773] Timer tick, asking Buildbucket for the build status
[10:53:56.811] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:56.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:113:0) after 1m0s
[10:54:56.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:113:0)
[10:54:56.815] Timer tick, asking Buildbucket for the build status
[10:54:57.133] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:57.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:114:0) after 1m0s
[10:55:57.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:114:0)
[10:55:57.160] Timer tick, asking Buildbucket for the build status
[10:55:57.375] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:57.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:115:0) after 1m0s
[10:56:57.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:115:0)
[10:56:57.395] Timer tick, asking Buildbucket for the build status
[10:56:57.453] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:57.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:116:0) after 1m0s
[10:57:57.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:116:0)
[10:57:57.426] Timer tick, asking Buildbucket for the build status
[10:57:57.468] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:57.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:117:0) after 1m0s
[10:58:57.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:117:0)
[10:58:57.481] Timer tick, asking Buildbucket for the build status
[10:58:57.558] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:118:0) after 1m0s
[10:59:57.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:118:0)
[10:59:57.580] Timer tick, asking Buildbucket for the build status
[10:59:57.626] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:57.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:119:0) after 1m0s
[11:00:57.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:119:0)
[11:00:57.649] Timer tick, asking Buildbucket for the build status
[11:00:57.873] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:57.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:120:0) after 1m0s
[11:01:57.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:120:0)
[11:01:57.881] Timer tick, asking Buildbucket for the build status
[11:01:57.974] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:57.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:121:0) after 1m0s
[11:02:57.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:121:0)
[11:02:57.998] Timer tick, asking Buildbucket for the build status
[11:02:58.097] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:58.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:122:0) after 1m0s
[11:03:58.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:122:0)
[11:03:58.121] Timer tick, asking Buildbucket for the build status
[11:03:58.245] Build 8915126950856440656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:58.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080557012976447808:123:0) after 1m0s
[11:04:26.860] Received PubSub notification, asking Buildbucket for the build status
[11:04:26.914] Build 8915126950856440656: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:04:26.914] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556276666368075",
"created_by": "project:fuchsia",
"created_ts": "1556269421675342",
"failure_reason": "BUILD_FAILURE",
"id": "8915126950856440656",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0eadd988662533f5ef89a2024dc7ebf830f16b67\"}}",
"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-txgt\", \"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\": \"0eadd988662533f5ef89a2024dc7ebf830f16b67\", \"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-txgt\"], \"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\": [\"4274-a0a5f8c\"], \"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": "1556269444726999",
"status": "COMPLETED",
"status_changed_ts": "1556269445075702",
"tags": [
"builder:clang",
"buildset:commit/git/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0eadd988662533f5ef89a2024dc7ebf830f16b67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080557012976447808",
"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/8915126950856440656/+/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:4471b45fc8e11a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1556276666552568",
"url": "https://ci.chromium.org/b/8915126950856440656",
"utcnow_ts": "1556276666906677"
}
}
[11:04:26.914] Invocation finished in 2h0m46.645107296s with status FAILED