[16:34:32.111] New invocation is queued and will start shortly
[16:34:33.253] Starting the invocation (attempt 1)
[16:34:33.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:34:33.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:34:33.300] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079713274382377488",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0460a3629b2564d9b34702ba05ab3f461533eca9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079713274382377488",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/0460a3629b2564d9b34702ba05ab3f461533eca9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0460a3629b2564d9b34702ba05ab3f461533eca9",
"gitiles_ref:refs/heads/master"
]
}
[16:34:34.194] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557074073380434",
"id": "8914283212389722736",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0460a3629b2564d9b34702ba05ab3f461533eca9\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557074073665000",
"tags": [
"builder:clang",
"buildset:commit/git/0460a3629b2564d9b34702ba05ab3f461533eca9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0460a3629b2564d9b34702ba05ab3f461533eca9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079713274382377488",
"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/8914283212389722736/+/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": "1557074073665299",
"url": "https://ci.chromium.org/b/8914283212389722736",
"utcnow_ts": "1557074074185126"
}
}
[16:34:34.194] Task URL: https://ci.chromium.org/b/8914283212389722736
[16:34:34.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:2:0) after 1m0s
[16:34:36.335] Received PubSub notification, asking Buildbucket for the build status
[16:34:36.493] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:2:0)
[16:35:34.163] Timer tick, asking Buildbucket for the build status
[16:35:34.285] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:4:0) after 1m0s
[16:36:34.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:4:0)
[16:36:34.301] Timer tick, asking Buildbucket for the build status
[16:36:34.388] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:34.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:5:0) after 1m0s
[16:37:34.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:5:0)
[16:37:34.420] Timer tick, asking Buildbucket for the build status
[16:37:34.568] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:34.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:6:0) after 1m0s
[16:38:34.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:6:0)
[16:38:34.586] Timer tick, asking Buildbucket for the build status
[16:38:34.698] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:34.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:7:0) after 1m0s
[16:39:34.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:7:0)
[16:39:34.683] Timer tick, asking Buildbucket for the build status
[16:39:34.859] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:34.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:8:0) after 1m0s
[16:40:34.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:8:0)
[16:40:34.878] Timer tick, asking Buildbucket for the build status
[16:40:34.924] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:34.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:9:0) after 1m0s
[16:41:34.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:9:0)
[16:41:34.943] Timer tick, asking Buildbucket for the build status
[16:41:35.244] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:35.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:10:0) after 1m0s
[16:42:35.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:10:0)
[16:42:35.262] Timer tick, asking Buildbucket for the build status
[16:42:35.367] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:35.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:11:0) after 1m0s
[16:43:35.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:11:0)
[16:43:35.427] Timer tick, asking Buildbucket for the build status
[16:43:35.596] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:35.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:12:0) after 1m0s
[16:44:35.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:12:0)
[16:44:35.694] Timer tick, asking Buildbucket for the build status
[16:44:35.808] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:35.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:13:0) after 1m0s
[16:45:35.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:13:0)
[16:45:35.869] Timer tick, asking Buildbucket for the build status
[16:45:35.909] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:35.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:14:0) after 1m0s
[16:46:35.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:14:0)
[16:46:35.917] Timer tick, asking Buildbucket for the build status
[16:46:35.989] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:35.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:15:0) after 1m0s
[16:47:36.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:15:0)
[16:47:36.091] Timer tick, asking Buildbucket for the build status
[16:47:36.165] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:36.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:16:0) after 1m0s
[16:48:36.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:16:0)
[16:48:36.185] Timer tick, asking Buildbucket for the build status
[16:48:36.226] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:36.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:17:0) after 1m0s
[16:49:36.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:17:0)
[16:49:36.249] Timer tick, asking Buildbucket for the build status
[16:49:36.561] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:36.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:18:0) after 1m0s
[16:50:36.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:18:0)
[16:50:36.584] Timer tick, asking Buildbucket for the build status
[16:50:36.673] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:36.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:19:0) after 1m0s
[16:51:36.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:19:0)
[16:51:36.691] Timer tick, asking Buildbucket for the build status
[16:51:36.784] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:36.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:20:0) after 1m0s
[16:52:36.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:20:0)
[16:52:36.809] Timer tick, asking Buildbucket for the build status
[16:52:36.865] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:36.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:21:0) after 1m0s
[16:53:36.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:21:0)
[16:53:36.885] Timer tick, asking Buildbucket for the build status
[16:53:37.068] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:37.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:22:0) after 1m0s
[16:54:37.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:22:0)
[16:54:37.050] Timer tick, asking Buildbucket for the build status
[16:54:37.176] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:37.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:23:0) after 1m0s
[16:55:37.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:23:0)
[16:55:37.194] Timer tick, asking Buildbucket for the build status
[16:55:37.296] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:37.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:24:0) after 1m0s
[16:56:37.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:24:0)
[16:56:37.315] Timer tick, asking Buildbucket for the build status
[16:56:37.377] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:37.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:25:0) after 1m0s
[16:57:37.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:25:0)
[16:57:37.442] Timer tick, asking Buildbucket for the build status
[16:57:37.515] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:37.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:26:0) after 1m0s
[16:58:37.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:26:0)
[16:58:37.534] Timer tick, asking Buildbucket for the build status
[16:58:37.602] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:37.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:27:0) after 1m0s
[16:59:37.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:27:0)
[16:59:37.621] Timer tick, asking Buildbucket for the build status
[16:59:37.943] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:37.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:28:0) after 1m0s
[17:00:37.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:28:0)
[17:00:37.962] Timer tick, asking Buildbucket for the build status
[17:00:38.290] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:38.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:29:0) after 1m0s
[17:01:38.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:29:0)
[17:01:38.307] Timer tick, asking Buildbucket for the build status
[17:01:38.354] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:38.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:30:0) after 1m0s
[17:02:38.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:30:0)
[17:02:38.395] Timer tick, asking Buildbucket for the build status
[17:02:38.537] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:38.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:31:0) after 1m0s
[17:03:38.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:31:0)
[17:03:38.556] Timer tick, asking Buildbucket for the build status
[17:03:38.627] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:38.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:32:0) after 1m0s
[17:04:38.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:32:0)
[17:04:38.644] Timer tick, asking Buildbucket for the build status
[17:04:38.708] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:38.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:33:0) after 1m0s
[17:05:38.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:33:0)
[17:05:38.725] Timer tick, asking Buildbucket for the build status
[17:05:38.836] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:38.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:34:0) after 1m0s
[17:06:38.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:34:0)
[17:06:38.854] Timer tick, asking Buildbucket for the build status
[17:06:38.967] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:38.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:35:0) after 1m0s
[17:07:38.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:35:0)
[17:07:38.967] Timer tick, asking Buildbucket for the build status
[17:07:39.074] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:36:0) after 1m0s
[17:08:39.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:36:0)
[17:08:39.103] Timer tick, asking Buildbucket for the build status
[17:08:39.164] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:39.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:37:0) after 1m0s
[17:09:39.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:37:0)
[17:09:39.173] Timer tick, asking Buildbucket for the build status
[17:09:39.234] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:39.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:38:0) after 1m0s
[17:10:39.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:38:0)
[17:10:39.251] Timer tick, asking Buildbucket for the build status
[17:10:39.419] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:39.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:39:0) after 1m0s
[17:11:39.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:39:0)
[17:11:39.442] Timer tick, asking Buildbucket for the build status
[17:11:39.511] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:39.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:40:0) after 1m0s
[17:12:39.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:40:0)
[17:12:39.539] Timer tick, asking Buildbucket for the build status
[17:12:39.620] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:39.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:41:0) after 1m0s
[17:13:39.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:41:0)
[17:13:39.622] Timer tick, asking Buildbucket for the build status
[17:13:39.915] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:39.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:42:0) after 1m0s
[17:14:39.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:42:0)
[17:14:39.934] Timer tick, asking Buildbucket for the build status
[17:14:40.074] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:40.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:43:0) after 1m0s
[17:15:40.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:43:0)
[17:15:40.095] Timer tick, asking Buildbucket for the build status
[17:15:40.192] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:40.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:44:0) after 1m0s
[17:16:40.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:44:0)
[17:16:40.211] Timer tick, asking Buildbucket for the build status
[17:16:40.302] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:40.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:45:0) after 1m0s
[17:17:40.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:45:0)
[17:17:40.303] Timer tick, asking Buildbucket for the build status
[17:17:40.757] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:40.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:46:0) after 1m0s
[17:18:40.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:46:0)
[17:18:40.766] Timer tick, asking Buildbucket for the build status
[17:18:40.878] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:40.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:47:0) after 1m0s
[17:19:40.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:47:0)
[17:19:40.894] Timer tick, asking Buildbucket for the build status
[17:19:40.955] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:40.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:48:0) after 1m0s
[17:20:40.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:48:0)
[17:20:40.978] Timer tick, asking Buildbucket for the build status
[17:20:41.051] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:41.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:49:0) after 1m0s
[17:21:41.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:49:0)
[17:21:41.070] Timer tick, asking Buildbucket for the build status
[17:21:41.150] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:41.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:50:0) after 1m0s
[17:22:41.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:50:0)
[17:22:41.173] Timer tick, asking Buildbucket for the build status
[17:22:41.241] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:41.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:51:0) after 1m0s
[17:23:41.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:51:0)
[17:23:41.245] Timer tick, asking Buildbucket for the build status
[17:23:41.326] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:41.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:52:0) after 1m0s
[17:24:41.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:52:0)
[17:24:41.345] Timer tick, asking Buildbucket for the build status
[17:24:41.470] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:41.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:53:0) after 1m0s
[17:25:41.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:53:0)
[17:25:41.485] Timer tick, asking Buildbucket for the build status
[17:25:41.640] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:41.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:54:0) after 1m0s
[17:26:41.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:54:0)
[17:26:41.677] Timer tick, asking Buildbucket for the build status
[17:26:42.146] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:42.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:55:0) after 1m0s
[17:27:42.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:55:0)
[17:27:42.172] Timer tick, asking Buildbucket for the build status
[17:27:42.360] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:42.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:56:0) after 1m0s
[17:28:42.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:56:0)
[17:28:42.338] Timer tick, asking Buildbucket for the build status
[17:28:42.867] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:42.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:57:0) after 1m0s
[17:29:42.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:57:0)
[17:29:42.890] Timer tick, asking Buildbucket for the build status
[17:29:42.976] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:42.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:58:0) after 1m0s
[17:30:42.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:58:0)
[17:30:42.953] Timer tick, asking Buildbucket for the build status
[17:30:42.992] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:42.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:59:0) after 1m0s
[17:31:43.010] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:59:0)
[17:31:43.010] Timer tick, asking Buildbucket for the build status
[17:31:43.081] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:43.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:60:0) after 1m0s
[17:32:43.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:60:0)
[17:32:43.099] Timer tick, asking Buildbucket for the build status
[17:32:43.199] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:43.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:61:0) after 1m0s
[17:33:43.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:61:0)
[17:33:43.200] Timer tick, asking Buildbucket for the build status
[17:33:43.274] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:43.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:62:0) after 1m0s
[17:34:43.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:62:0)
[17:34:43.296] Timer tick, asking Buildbucket for the build status
[17:34:43.524] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:43.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:63:0) after 1m0s
[17:35:43.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:63:0)
[17:35:43.506] Timer tick, asking Buildbucket for the build status
[17:35:43.678] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:43.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:64:0) after 1m0s
[17:36:43.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:64:0)
[17:36:43.737] Timer tick, asking Buildbucket for the build status
[17:36:43.834] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:43.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:65:0) after 1m0s
[17:37:43.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:65:0)
[17:37:43.865] Timer tick, asking Buildbucket for the build status
[17:37:43.934] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:43.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:66:0) after 1m0s
[17:38:43.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:66:0)
[17:38:43.953] Timer tick, asking Buildbucket for the build status
[17:38:44.145] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:44.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:67:0) after 1m0s
[17:39:44.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:67:0)
[17:39:44.167] Timer tick, asking Buildbucket for the build status
[17:39:44.252] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:44.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:68:0) after 1m0s
[17:40:44.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:68:0)
[17:40:44.278] Timer tick, asking Buildbucket for the build status
[17:40:44.389] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:44.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:69:0) after 1m0s
[17:41:44.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:69:0)
[17:41:44.471] Timer tick, asking Buildbucket for the build status
[17:41:44.515] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:44.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:70:0) after 1m0s
[17:42:44.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:70:0)
[17:42:44.535] Timer tick, asking Buildbucket for the build status
[17:42:44.673] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:44.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:71:0) after 1m0s
[17:43:44.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:71:0)
[17:43:44.696] Timer tick, asking Buildbucket for the build status
[17:43:44.780] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:44.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:72:0) after 1m0s
[17:44:44.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:72:0)
[17:44:44.798] Timer tick, asking Buildbucket for the build status
[17:44:44.895] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:44.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:73:0) after 1m0s
[17:45:44.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:73:0)
[17:45:44.916] Timer tick, asking Buildbucket for the build status
[17:45:45.070] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:45.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:74:0) after 1m0s
[17:46:45.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:74:0)
[17:46:45.108] Timer tick, asking Buildbucket for the build status
[17:46:45.182] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:45.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:75:0) after 1m0s
[17:47:45.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:75:0)
[17:47:45.215] Timer tick, asking Buildbucket for the build status
[17:47:45.298] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:45.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:76:0) after 1m0s
[17:48:45.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:76:0)
[17:48:45.330] Timer tick, asking Buildbucket for the build status
[17:48:45.399] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:45.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:77:0) after 1m0s
[17:49:45.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:77:0)
[17:49:45.421] Timer tick, asking Buildbucket for the build status
[17:49:45.595] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:45.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:78:0) after 1m0s
[17:50:45.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:78:0)
[17:50:45.619] Timer tick, asking Buildbucket for the build status
[17:50:45.795] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:45.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:79:0) after 1m0s
[17:51:45.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:79:0)
[17:51:45.830] Timer tick, asking Buildbucket for the build status
[17:51:46.014] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:46.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:80:0) after 1m0s
[17:52:46.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:80:0)
[17:52:46.035] Timer tick, asking Buildbucket for the build status
[17:52:46.133] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:46.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:81:0) after 1m0s
[17:53:46.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:81:0)
[17:53:46.153] Timer tick, asking Buildbucket for the build status
[17:53:46.252] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:82:0) after 1m0s
[17:54:46.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:82:0)
[17:54:46.297] Timer tick, asking Buildbucket for the build status
[17:54:46.345] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:46.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:83:0) after 1m0s
[17:55:46.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:83:0)
[17:55:46.368] Timer tick, asking Buildbucket for the build status
[17:55:46.456] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:46.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:84:0) after 1m0s
[17:56:46.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:84:0)
[17:56:46.488] Timer tick, asking Buildbucket for the build status
[17:56:46.907] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:46.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:85:0) after 1m0s
[17:57:46.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:85:0)
[17:57:46.964] Timer tick, asking Buildbucket for the build status
[17:57:47.059] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:47.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:86:0) after 1m0s
[17:58:47.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:86:0)
[17:58:47.079] Timer tick, asking Buildbucket for the build status
[17:58:47.180] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:47.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:87:0) after 1m0s
[17:59:47.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:87:0)
[17:59:47.200] Timer tick, asking Buildbucket for the build status
[17:59:47.392] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:47.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:88:0) after 1m0s
[18:00:47.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:88:0)
[18:00:47.417] Timer tick, asking Buildbucket for the build status
[18:00:47.477] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:47.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:89:0) after 1m0s
[18:01:47.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:89:0)
[18:01:47.502] Timer tick, asking Buildbucket for the build status
[18:01:47.650] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:47.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:90:0) after 1m0s
[18:02:47.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:90:0)
[18:02:47.673] Timer tick, asking Buildbucket for the build status
[18:02:47.725] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:47.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:91:0) after 1m0s
[18:03:47.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:91:0)
[18:03:47.748] Timer tick, asking Buildbucket for the build status
[18:03:47.938] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:47.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:92:0) after 1m0s
[18:04:47.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:92:0)
[18:04:47.956] Timer tick, asking Buildbucket for the build status
[18:04:48.028] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:48.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:93:0) after 1m0s
[18:05:47.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:93:0)
[18:05:47.999] Timer tick, asking Buildbucket for the build status
[18:05:48.090] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:48.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:94:0) after 1m0s
[18:06:48.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:94:0)
[18:06:48.115] Timer tick, asking Buildbucket for the build status
[18:06:48.218] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:95:0) after 1m0s
[18:07:48.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:95:0)
[18:07:48.240] Timer tick, asking Buildbucket for the build status
[18:07:48.353] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:96:0) after 1m0s
[18:08:48.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:96:0)
[18:08:48.364] Timer tick, asking Buildbucket for the build status
[18:08:48.555] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:48.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:97:0) after 1m0s
[18:09:48.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:97:0)
[18:09:48.574] Timer tick, asking Buildbucket for the build status
[18:09:48.612] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:48.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:98:0) after 1m0s
[18:10:48.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:98:0)
[18:10:48.608] Timer tick, asking Buildbucket for the build status
[18:10:48.646] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:48.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:99:0) after 1m0s
[18:11:48.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:99:0)
[18:11:48.667] Timer tick, asking Buildbucket for the build status
[18:11:48.797] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:48.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:100:0) after 1m0s
[18:12:48.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:100:0)
[18:12:48.822] Timer tick, asking Buildbucket for the build status
[18:12:48.974] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:48.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:101:0) after 1m0s
[18:13:48.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:101:0)
[18:13:48.998] Timer tick, asking Buildbucket for the build status
[18:13:49.136] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:49.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:102:0) after 1m0s
[18:14:49.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:102:0)
[18:14:49.139] Timer tick, asking Buildbucket for the build status
[18:14:49.208] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:49.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:103:0) after 1m0s
[18:15:49.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:103:0)
[18:15:49.217] Timer tick, asking Buildbucket for the build status
[18:15:49.343] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:49.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:104:0) after 1m0s
[18:16:49.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:104:0)
[18:16:49.348] Timer tick, asking Buildbucket for the build status
[18:16:49.462] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:49.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:105:0) after 1m0s
[18:17:49.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:105:0)
[18:17:49.489] Timer tick, asking Buildbucket for the build status
[18:17:49.668] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:49.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:106:0) after 1m0s
[18:18:49.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:106:0)
[18:18:49.688] Timer tick, asking Buildbucket for the build status
[18:18:49.872] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:49.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:107:0) after 1m0s
[18:19:49.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:107:0)
[18:19:49.900] Timer tick, asking Buildbucket for the build status
[18:19:49.941] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:49.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:108:0) after 1m0s
[18:20:50.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:108:0)
[18:20:50.006] Timer tick, asking Buildbucket for the build status
[18:20:50.107] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:50.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:109:0) after 1m0s
[18:21:50.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:109:0)
[18:21:50.130] Timer tick, asking Buildbucket for the build status
[18:21:50.304] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:50.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:110:0) after 1m0s
[18:22:50.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:110:0)
[18:22:50.327] Timer tick, asking Buildbucket for the build status
[18:22:50.366] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:50.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:111:0) after 1m0s
[18:23:50.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:111:0)
[18:23:50.389] Timer tick, asking Buildbucket for the build status
[18:23:50.456] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:50.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:112:0) after 1m0s
[18:24:50.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:112:0)
[18:24:50.482] Timer tick, asking Buildbucket for the build status
[18:24:50.571] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:50.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:113:0) after 1m0s
[18:25:50.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:113:0)
[18:25:50.591] Timer tick, asking Buildbucket for the build status
[18:25:50.763] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:50.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:114:0) after 1m0s
[18:26:50.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:114:0)
[18:26:50.816] Timer tick, asking Buildbucket for the build status
[18:26:51.052] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:51.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:115:0) after 1m0s
[18:27:51.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:115:0)
[18:27:51.074] Timer tick, asking Buildbucket for the build status
[18:27:51.245] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:51.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:116:0) after 1m0s
[18:28:51.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:116:0)
[18:28:51.254] Timer tick, asking Buildbucket for the build status
[18:28:51.303] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:51.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:117:0) after 1m0s
[18:29:51.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:117:0)
[18:29:51.333] Timer tick, asking Buildbucket for the build status
[18:29:51.404] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:51.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:118:0) after 1m0s
[18:30:51.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:118:0)
[18:30:51.432] Timer tick, asking Buildbucket for the build status
[18:30:51.520] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:51.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:119:0) after 1m0s
[18:31:51.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:119:0)
[18:31:51.525] Timer tick, asking Buildbucket for the build status
[18:31:51.699] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:51.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:120:0) after 1m0s
[18:32:51.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:120:0)
[18:32:51.720] Timer tick, asking Buildbucket for the build status
[18:32:51.850] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:51.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:121:0) after 1m0s
[18:33:51.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:121:0)
[18:33:51.879] Timer tick, asking Buildbucket for the build status
[18:33:51.946] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:51.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:122:0) after 1m0s
[18:34:51.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:122:0)
[18:34:51.981] Timer tick, asking Buildbucket for the build status
[18:34:52.103] Build 8914283212389722736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:52.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079713274382377488:123:0) after 1m0s
[18:35:12.240] Received PubSub notification, asking Buildbucket for the build status
[18:35:12.320] Build 8914283212389722736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:35:12.320] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557081311723574",
"created_by": "project:fuchsia",
"created_ts": "1557074073380434",
"failure_reason": "BUILD_FAILURE",
"id": "8914283212389722736",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0460a3629b2564d9b34702ba05ab3f461533eca9\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-003729e9-us-central1-c-tzsv\", \"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\": \"0460a3629b2564d9b34702ba05ab3f461533eca9\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-003729e9-us-central1-c-tzsv\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190426-c90e1d5-prod\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4284-fd5e65b\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1557074075543361",
"status": "COMPLETED",
"status_changed_ts": "1557074076007818",
"tags": [
"builder:clang",
"buildset:commit/git/0460a3629b2564d9b34702ba05ab3f461533eca9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0460a3629b2564d9b34702ba05ab3f461533eca9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079713274382377488",
"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/8914283212389722736/+/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:44a1aa637f90ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557081311789574",
"url": "https://ci.chromium.org/b/8914283212389722736",
"utcnow_ts": "1557081312313202"
}
}
[18:35:12.320] Invocation finished in 2h0m40.224576707s with status FAILED