[18:42:40.177] New invocation is queued and will start shortly
[18:42:41.328] Starting the invocation (attempt 1)
[18:42:41.359] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:42:41.360] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:42:41.360] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083238494555522464",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"eaf4df47821672d3117409d98dae341ea4e972d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083238494555522464",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/eaf4df47821672d3117409d98dae341ea4e972d2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eaf4df47821672d3117409d98dae341ea4e972d2",
"gitiles_ref:refs/heads/master"
]
}
[18:42:41.981] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553712161541744",
"id": "8917808432458595824",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eaf4df47821672d3117409d98dae341ea4e972d2\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553712161830078",
"tags": [
"builder:clang",
"buildset:commit/git/eaf4df47821672d3117409d98dae341ea4e972d2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eaf4df47821672d3117409d98dae341ea4e972d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083238494555522464",
"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/8917808432458595824/+/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": "1553712161830201",
"url": "https://ci.chromium.org/b/8917808432458595824",
"utcnow_ts": "1553712161971842"
}
}
[18:42:41.981] Task URL: https://ci.chromium.org/b/8917808432458595824
[18:42:41.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:2:0) after 1m0s
[18:43:01.982] Received PubSub notification, asking Buildbucket for the build status
[18:43:02.207] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:41.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:2:0)
[18:43:41.998] Timer tick, asking Buildbucket for the build status
[18:43:42.107] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:42.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:4:0) after 1m0s
[18:44:42.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:4:0)
[18:44:42.131] Timer tick, asking Buildbucket for the build status
[18:44:42.301] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:42.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:5:0) after 1m0s
[18:45:42.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:5:0)
[18:45:42.314] Timer tick, asking Buildbucket for the build status
[18:45:42.501] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:42.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:6:0) after 1m0s
[18:46:42.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:6:0)
[18:46:42.518] Timer tick, asking Buildbucket for the build status
[18:46:42.572] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:42.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:7:0) after 1m0s
[18:47:42.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:7:0)
[18:47:42.564] Timer tick, asking Buildbucket for the build status
[18:47:42.607] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:42.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:8:0) after 1m0s
[18:48:42.631] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:8:0)
[18:48:42.631] Timer tick, asking Buildbucket for the build status
[18:48:42.690] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:42.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:9:0) after 1m0s
[18:49:42.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:9:0)
[18:49:42.725] Timer tick, asking Buildbucket for the build status
[18:49:42.768] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:42.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:10:0) after 1m0s
[18:50:42.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:10:0)
[18:50:42.752] Timer tick, asking Buildbucket for the build status
[18:50:42.818] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:42.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:11:0) after 1m0s
[18:51:42.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:11:0)
[18:51:42.836] Timer tick, asking Buildbucket for the build status
[18:51:42.890] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:42.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:12:0) after 1m0s
[18:52:42.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:12:0)
[18:52:42.917] Timer tick, asking Buildbucket for the build status
[18:52:42.967] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:42.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:13:0) after 1m0s
[18:53:42.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:13:0)
[18:53:42.997] Timer tick, asking Buildbucket for the build status
[18:53:43.079] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:43.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:14:0) after 1m0s
[18:54:43.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:14:0)
[18:54:43.105] Timer tick, asking Buildbucket for the build status
[18:54:43.155] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:43.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:15:0) after 1m0s
[18:55:43.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:15:0)
[18:55:43.175] Timer tick, asking Buildbucket for the build status
[18:55:43.220] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:43.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:16:0) after 1m0s
[18:56:43.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:16:0)
[18:56:43.289] Timer tick, asking Buildbucket for the build status
[18:56:43.362] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:43.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:17:0) after 1m0s
[18:57:43.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:17:0)
[18:57:43.400] Timer tick, asking Buildbucket for the build status
[18:57:43.443] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:43.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:18:0) after 1m0s
[18:58:43.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:18:0)
[18:58:43.463] Timer tick, asking Buildbucket for the build status
[18:58:43.621] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:43.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:19:0) after 1m0s
[18:59:43.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:19:0)
[18:59:43.592] Timer tick, asking Buildbucket for the build status
[18:59:43.648] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:43.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:20:0) after 1m0s
[19:00:43.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:20:0)
[19:00:43.668] Timer tick, asking Buildbucket for the build status
[19:00:43.743] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:43.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:21:0) after 1m0s
[19:01:43.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:21:0)
[19:01:43.766] Timer tick, asking Buildbucket for the build status
[19:01:43.821] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:43.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:22:0) after 1m0s
[19:02:43.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:22:0)
[19:02:43.841] Timer tick, asking Buildbucket for the build status
[19:02:43.898] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:43.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:23:0) after 1m0s
[19:03:43.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:23:0)
[19:03:43.920] Timer tick, asking Buildbucket for the build status
[19:03:44.007] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:44.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:24:0) after 1m0s
[19:04:44.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:24:0)
[19:04:44.045] Timer tick, asking Buildbucket for the build status
[19:04:44.123] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:44.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:25:0) after 1m0s
[19:05:44.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:25:0)
[19:05:44.155] Timer tick, asking Buildbucket for the build status
[19:05:44.243] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:44.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:26:0) after 1m0s
[19:06:44.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:26:0)
[19:06:44.272] Timer tick, asking Buildbucket for the build status
[19:06:44.333] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:44.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:27:0) after 1m0s
[19:07:44.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:27:0)
[19:07:44.392] Timer tick, asking Buildbucket for the build status
[19:07:44.556] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:44.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:28:0) after 1m0s
[19:08:44.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:28:0)
[19:08:44.583] Timer tick, asking Buildbucket for the build status
[19:08:44.642] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:44.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:29:0) after 1m0s
[19:09:44.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:29:0)
[19:09:44.668] Timer tick, asking Buildbucket for the build status
[19:09:44.719] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:44.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:30:0) after 1m0s
[19:10:44.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:30:0)
[19:10:44.772] Timer tick, asking Buildbucket for the build status
[19:10:44.978] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:44.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:31:0) after 1m0s
[19:11:45.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:31:0)
[19:11:45.019] Timer tick, asking Buildbucket for the build status
[19:11:45.288] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:45.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:32:0) after 1m0s
[19:12:45.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:32:0)
[19:12:45.299] Timer tick, asking Buildbucket for the build status
[19:12:45.344] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:45.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:33:0) after 1m0s
[19:13:45.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:33:0)
[19:13:45.373] Timer tick, asking Buildbucket for the build status
[19:13:45.604] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:45.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:34:0) after 1m0s
[19:14:45.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:34:0)
[19:14:45.588] Timer tick, asking Buildbucket for the build status
[19:14:45.749] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:45.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:35:0) after 1m0s
[19:15:45.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:35:0)
[19:15:45.781] Timer tick, asking Buildbucket for the build status
[19:15:45.844] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:45.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:36:0) after 1m0s
[19:16:45.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:36:0)
[19:16:45.869] Timer tick, asking Buildbucket for the build status
[19:16:45.907] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:45.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:37:0) after 1m0s
[19:17:45.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:37:0)
[19:17:45.926] Timer tick, asking Buildbucket for the build status
[19:17:46.011] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:46.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:38:0) after 1m0s
[19:18:46.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:38:0)
[19:18:46.029] Timer tick, asking Buildbucket for the build status
[19:18:46.161] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:46.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:39:0) after 1m0s
[19:19:46.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:39:0)
[19:19:46.183] Timer tick, asking Buildbucket for the build status
[19:19:46.283] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:46.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:40:0) after 1m0s
[19:20:46.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:40:0)
[19:20:46.264] Timer tick, asking Buildbucket for the build status
[19:20:46.306] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:46.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:41:0) after 1m0s
[19:21:46.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:41:0)
[19:21:46.394] Timer tick, asking Buildbucket for the build status
[19:21:46.456] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:46.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:42:0) after 1m0s
[19:22:46.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:42:0)
[19:22:46.483] Timer tick, asking Buildbucket for the build status
[19:22:46.528] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:46.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:43:0) after 1m0s
[19:23:46.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:43:0)
[19:23:46.553] Timer tick, asking Buildbucket for the build status
[19:23:46.623] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:46.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:44:0) after 1m0s
[19:24:46.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:44:0)
[19:24:46.644] Timer tick, asking Buildbucket for the build status
[19:24:46.692] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:46.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:45:0) after 1m0s
[19:25:46.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:45:0)
[19:25:46.719] Timer tick, asking Buildbucket for the build status
[19:25:46.827] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:46.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:46:0) after 1m0s
[19:26:46.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:46:0)
[19:26:46.803] Timer tick, asking Buildbucket for the build status
[19:26:46.973] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:46.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:47:0) after 1m0s
[19:27:46.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:47:0)
[19:27:46.996] Timer tick, asking Buildbucket for the build status
[19:27:47.041] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:47.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:48:0) after 1m0s
[19:28:47.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:48:0)
[19:28:47.121] Timer tick, asking Buildbucket for the build status
[19:28:47.280] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:47.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:49:0) after 1m0s
[19:29:47.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:49:0)
[19:29:47.349] Timer tick, asking Buildbucket for the build status
[19:29:47.424] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:47.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:50:0) after 1m0s
[19:30:47.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:50:0)
[19:30:47.441] Timer tick, asking Buildbucket for the build status
[19:30:47.526] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:47.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:51:0) after 1m0s
[19:31:47.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:51:0)
[19:31:47.539] Timer tick, asking Buildbucket for the build status
[19:31:47.587] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:47.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:52:0) after 1m0s
[19:32:47.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:52:0)
[19:32:47.614] Timer tick, asking Buildbucket for the build status
[19:32:47.678] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:47.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:53:0) after 1m0s
[19:33:47.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:53:0)
[19:33:47.698] Timer tick, asking Buildbucket for the build status
[19:33:47.763] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:47.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:54:0) after 1m0s
[19:34:47.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:54:0)
[19:34:47.795] Timer tick, asking Buildbucket for the build status
[19:34:47.944] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:47.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:55:0) after 1m0s
[19:35:47.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:55:0)
[19:35:47.966] Timer tick, asking Buildbucket for the build status
[19:35:48.013] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:48.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:56:0) after 1m0s
[19:36:47.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:56:0)
[19:36:47.998] Timer tick, asking Buildbucket for the build status
[19:36:48.105] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:48.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:57:0) after 1m0s
[19:37:48.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:57:0)
[19:37:48.147] Timer tick, asking Buildbucket for the build status
[19:37:48.352] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:48.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:58:0) after 1m0s
[19:38:48.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:58:0)
[19:38:48.392] Timer tick, asking Buildbucket for the build status
[19:38:48.598] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:48.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:59:0) after 1m0s
[19:39:48.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:59:0)
[19:39:48.624] Timer tick, asking Buildbucket for the build status
[19:39:48.712] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:48.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:60:0) after 1m0s
[19:40:48.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:60:0)
[19:40:48.734] Timer tick, asking Buildbucket for the build status
[19:40:48.797] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:48.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:61:0) after 1m0s
[19:41:48.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:61:0)
[19:41:48.819] Timer tick, asking Buildbucket for the build status
[19:41:48.878] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:48.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:62:0) after 1m0s
[19:42:48.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:62:0)
[19:42:48.900] Timer tick, asking Buildbucket for the build status
[19:42:48.949] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:48.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:63:0) after 1m0s
[19:43:48.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:63:0)
[19:43:48.972] Timer tick, asking Buildbucket for the build status
[19:43:49.030] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:49.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:64:0) after 1m0s
[19:44:49.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:64:0)
[19:44:49.053] Timer tick, asking Buildbucket for the build status
[19:44:49.196] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:49.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:65:0) after 1m0s
[19:45:49.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:65:0)
[19:45:49.216] Timer tick, asking Buildbucket for the build status
[19:45:49.314] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:49.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:66:0) after 1m0s
[19:46:49.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:66:0)
[19:46:49.351] Timer tick, asking Buildbucket for the build status
[19:46:49.394] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:49.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:67:0) after 1m0s
[19:47:49.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:67:0)
[19:47:49.390] Timer tick, asking Buildbucket for the build status
[19:47:49.434] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:49.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:68:0) after 1m0s
[19:48:49.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:68:0)
[19:48:49.470] Timer tick, asking Buildbucket for the build status
[19:48:49.520] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:49.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:69:0) after 1m0s
[19:49:49.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:69:0)
[19:49:49.542] Timer tick, asking Buildbucket for the build status
[19:49:49.605] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:49.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:70:0) after 1m0s
[19:50:49.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:70:0)
[19:50:49.596] Timer tick, asking Buildbucket for the build status
[19:50:49.787] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:49.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:71:0) after 1m0s
[19:51:49.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:71:0)
[19:51:49.781] Timer tick, asking Buildbucket for the build status
[19:51:49.834] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:49.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:72:0) after 1m0s
[19:52:49.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:72:0)
[19:52:49.859] Timer tick, asking Buildbucket for the build status
[19:52:49.902] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:49.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:73:0) after 1m0s
[19:53:49.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:73:0)
[19:53:49.908] Timer tick, asking Buildbucket for the build status
[19:53:49.952] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:49.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:74:0) after 1m0s
[19:54:49.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:74:0)
[19:54:49.978] Timer tick, asking Buildbucket for the build status
[19:54:50.487] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:50.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:75:0) after 1m0s
[19:55:50.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:75:0)
[19:55:50.486] Timer tick, asking Buildbucket for the build status
[19:55:50.569] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:50.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:76:0) after 1m0s
[19:56:50.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:76:0)
[19:56:50.557] Timer tick, asking Buildbucket for the build status
[19:56:50.628] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:50.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:77:0) after 1m0s
[19:57:50.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:77:0)
[19:57:50.650] Timer tick, asking Buildbucket for the build status
[19:57:50.798] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:50.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:78:0) after 1m0s
[19:58:50.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:78:0)
[19:58:50.793] Timer tick, asking Buildbucket for the build status
[19:58:50.838] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:50.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:79:0) after 1m0s
[19:59:50.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:79:0)
[19:59:50.862] Timer tick, asking Buildbucket for the build status
[19:59:50.901] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:50.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:80:0) after 1m0s
[20:00:50.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:80:0)
[20:00:50.906] Timer tick, asking Buildbucket for the build status
[20:00:50.973] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:50.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:81:0) after 1m0s
[20:01:50.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:81:0)
[20:01:50.997] Timer tick, asking Buildbucket for the build status
[20:01:51.105] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:51.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:82:0) after 1m0s
[20:02:51.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:82:0)
[20:02:51.127] Timer tick, asking Buildbucket for the build status
[20:02:51.179] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:51.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:83:0) after 1m0s
[20:03:51.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:83:0)
[20:03:51.207] Timer tick, asking Buildbucket for the build status
[20:03:51.262] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:51.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:84:0) after 1m0s
[20:04:51.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:84:0)
[20:04:51.296] Timer tick, asking Buildbucket for the build status
[20:04:51.344] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:51.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:85:0) after 1m0s
[20:05:51.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:85:0)
[20:05:51.367] Timer tick, asking Buildbucket for the build status
[20:05:51.433] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:51.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:86:0) after 1m0s
[20:06:51.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:86:0)
[20:06:51.455] Timer tick, asking Buildbucket for the build status
[20:06:51.492] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:51.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:87:0) after 1m0s
[20:07:51.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:87:0)
[20:07:51.522] Timer tick, asking Buildbucket for the build status
[20:07:51.615] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:51.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:88:0) after 1m0s
[20:08:51.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:88:0)
[20:08:51.610] Timer tick, asking Buildbucket for the build status
[20:08:51.667] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:51.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:89:0) after 1m0s
[20:09:51.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:89:0)
[20:09:51.681] Timer tick, asking Buildbucket for the build status
[20:09:51.739] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:51.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:90:0) after 1m0s
[20:10:51.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:90:0)
[20:10:51.767] Timer tick, asking Buildbucket for the build status
[20:10:51.841] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:51.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:91:0) after 1m0s
[20:11:51.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:91:0)
[20:11:51.860] Timer tick, asking Buildbucket for the build status
[20:11:51.906] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:51.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:92:0) after 1m0s
[20:12:51.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:92:0)
[20:12:51.928] Timer tick, asking Buildbucket for the build status
[20:12:52.053] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:52.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:93:0) after 1m0s
[20:13:52.055] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:93:0)
[20:13:52.055] Timer tick, asking Buildbucket for the build status
[20:13:52.095] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:52.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:94:0) after 1m0s
[20:14:52.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:94:0)
[20:14:52.117] Timer tick, asking Buildbucket for the build status
[20:14:52.212] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:52.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:95:0) after 1m0s
[20:15:52.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:95:0)
[20:15:52.239] Timer tick, asking Buildbucket for the build status
[20:15:52.364] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:96:0) after 1m0s
[20:16:52.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:96:0)
[20:16:52.402] Timer tick, asking Buildbucket for the build status
[20:16:52.499] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:52.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:97:0) after 1m0s
[20:17:52.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:97:0)
[20:17:52.522] Timer tick, asking Buildbucket for the build status
[20:17:52.580] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:52.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:98:0) after 1m0s
[20:18:52.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:98:0)
[20:18:52.572] Timer tick, asking Buildbucket for the build status
[20:18:52.631] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:52.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:99:0) after 1m0s
[20:19:52.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:99:0)
[20:19:52.666] Timer tick, asking Buildbucket for the build status
[20:19:52.711] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:52.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:100:0) after 1m0s
[20:20:52.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:100:0)
[20:20:52.699] Timer tick, asking Buildbucket for the build status
[20:20:52.770] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:52.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:101:0) after 1m0s
[20:21:52.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:101:0)
[20:21:52.793] Timer tick, asking Buildbucket for the build status
[20:21:53.037] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:53.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:102:0) after 1m0s
[20:22:53.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:102:0)
[20:22:53.101] Timer tick, asking Buildbucket for the build status
[20:22:53.613] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:53.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:103:0) after 1m0s
[20:23:53.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:103:0)
[20:23:53.713] Timer tick, asking Buildbucket for the build status
[20:23:53.777] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:53.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:104:0) after 1m0s
[20:24:53.787] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:104:0)
[20:24:53.787] Timer tick, asking Buildbucket for the build status
[20:24:53.834] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:53.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:105:0) after 1m0s
[20:25:53.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:105:0)
[20:25:53.856] Timer tick, asking Buildbucket for the build status
[20:25:53.899] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:53.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:106:0) after 1m0s
[20:26:53.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:106:0)
[20:26:53.904] Timer tick, asking Buildbucket for the build status
[20:26:53.946] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:53.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:107:0) after 1m0s
[20:27:53.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:107:0)
[20:27:53.963] Timer tick, asking Buildbucket for the build status
[20:27:54.097] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:54.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:108:0) after 1m0s
[20:28:54.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:108:0)
[20:28:54.120] Timer tick, asking Buildbucket for the build status
[20:28:54.163] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:54.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:109:0) after 1m0s
[20:29:54.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:109:0)
[20:29:54.186] Timer tick, asking Buildbucket for the build status
[20:29:54.258] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:54.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:110:0) after 1m0s
[20:30:54.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:110:0)
[20:30:54.278] Timer tick, asking Buildbucket for the build status
[20:30:54.329] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:54.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:111:0) after 1m0s
[20:31:54.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:111:0)
[20:31:54.352] Timer tick, asking Buildbucket for the build status
[20:31:54.401] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:54.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:112:0) after 1m0s
[20:32:54.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:112:0)
[20:32:54.421] Timer tick, asking Buildbucket for the build status
[20:32:54.469] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:54.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:113:0) after 1m0s
[20:33:54.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:113:0)
[20:33:54.509] Timer tick, asking Buildbucket for the build status
[20:33:54.602] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:54.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:114:0) after 1m0s
[20:34:54.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:114:0)
[20:34:54.642] Timer tick, asking Buildbucket for the build status
[20:34:54.696] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:54.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:115:0) after 1m0s
[20:35:54.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:115:0)
[20:35:54.697] Timer tick, asking Buildbucket for the build status
[20:35:54.740] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:54.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:116:0) after 1m0s
[20:36:54.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:116:0)
[20:36:54.736] Timer tick, asking Buildbucket for the build status
[20:36:54.780] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:54.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:117:0) after 1m0s
[20:37:54.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:117:0)
[20:37:54.810] Timer tick, asking Buildbucket for the build status
[20:37:54.873] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:54.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:118:0) after 1m0s
[20:38:54.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:118:0)
[20:38:54.912] Timer tick, asking Buildbucket for the build status
[20:38:55.006] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:55.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:119:0) after 1m0s
[20:39:55.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:119:0)
[20:39:55.018] Timer tick, asking Buildbucket for the build status
[20:39:55.195] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:55.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:120:0) after 1m0s
[20:40:55.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:120:0)
[20:40:55.197] Timer tick, asking Buildbucket for the build status
[20:40:55.319] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:55.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:121:0) after 1m0s
[20:41:55.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:121:0)
[20:41:55.343] Timer tick, asking Buildbucket for the build status
[20:41:55.388] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:55.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:122:0) after 1m0s
[20:42:55.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:122:0)
[20:42:55.420] Timer tick, asking Buildbucket for the build status
[20:42:55.466] Build 8917808432458595824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:55.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083238494555522464:123:0) after 1m0s
[20:43:30.068] Received PubSub notification, asking Buildbucket for the build status
[20:43:30.112] Build 8917808432458595824: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:43:30.112] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553719409631514",
"created_by": "project:fuchsia",
"created_ts": "1553712161541744",
"failure_reason": "BUILD_FAILURE",
"id": "8917808432458595824",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eaf4df47821672d3117409d98dae341ea4e972d2\"}}",
"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-7887e815-us-central1-b-f9j0\", \"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\": \"eaf4df47821672d3117409d98dae341ea4e972d2\", \"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-7887e815-us-central1-b-f9j0\"], \"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\": [\"4221-9c50278\"], \"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": "1553712179012483",
"status": "COMPLETED",
"status_changed_ts": "1553712179382793",
"tags": [
"builder:clang",
"buildset:commit/git/eaf4df47821672d3117409d98dae341ea4e972d2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eaf4df47821672d3117409d98dae341ea4e972d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083238494555522464",
"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/8917808432458595824/+/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:43d947ae51cc9c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553719409774052",
"url": "https://ci.chromium.org/b/8917808432458595824",
"utcnow_ts": "1553719410102475"
}
}
[20:43:30.112] Invocation finished in 2h0m49.954492986s with status FAILED