[00:17:56.100] New invocation is queued and will start shortly
[00:17:57.219] Starting the invocation (attempt 1)
[00:17:57.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:17:57.266] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:17:57.266] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079865313730501616",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079865313730501616",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"gitiles_ref:refs/heads/master"
]
}
[00:17:58.141] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556929077389506",
"id": "8914435251705584448",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556929077894895",
"tags": [
"builder:clang",
"buildset:commit/git/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079865313730501616",
"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/8914435251705584448/+/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": "1556929077907194",
"url": "https://ci.chromium.org/b/8914435251705584448",
"utcnow_ts": "1556929078133312"
}
}
[00:17:58.141] Task URL: https://ci.chromium.org/b/8914435251705584448
[00:17:58.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:2:0) after 1m0s
[00:18:32.981] Received PubSub notification, asking Buildbucket for the build status
[00:18:33.184] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:58.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:2:0)
[00:18:58.161] Timer tick, asking Buildbucket for the build status
[00:18:58.394] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:58.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:4:0) after 1m0s
[00:19:58.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:4:0)
[00:19:58.415] Timer tick, asking Buildbucket for the build status
[00:19:58.712] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:58.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:5:0) after 1m0s
[00:20:58.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:5:0)
[00:20:58.732] Timer tick, asking Buildbucket for the build status
[00:20:58.836] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:58.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:6:0) after 1m0s
[00:21:58.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:6:0)
[00:21:58.853] Timer tick, asking Buildbucket for the build status
[00:21:58.909] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:58.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:7:0) after 1m0s
[00:22:58.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:7:0)
[00:22:58.926] Timer tick, asking Buildbucket for the build status
[00:22:58.991] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:8:0) after 1m0s
[00:23:58.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:8:0)
[00:23:58.973] Timer tick, asking Buildbucket for the build status
[00:23:59.342] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:59.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:9:0) after 1m0s
[00:24:59.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:9:0)
[00:24:59.361] Timer tick, asking Buildbucket for the build status
[00:24:59.428] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:59.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:10:0) after 1m0s
[00:25:59.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:10:0)
[00:25:59.444] Timer tick, asking Buildbucket for the build status
[00:25:59.844] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:59.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:11:0) after 1m0s
[00:26:59.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:11:0)
[00:26:59.846] Timer tick, asking Buildbucket for the build status
[00:27:00.467] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:00.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:12:0) after 1m0s
[00:28:00.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:12:0)
[00:28:00.505] Timer tick, asking Buildbucket for the build status
[00:28:01.337] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:01.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:13:0) after 1m0s
[00:29:01.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:13:0)
[00:29:01.392] Timer tick, asking Buildbucket for the build status
[00:29:01.587] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:01.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:14:0) after 1m0s
[00:30:01.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:14:0)
[00:30:01.605] Timer tick, asking Buildbucket for the build status
[00:30:02.288] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:02.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:15:0) after 1m0s
[00:31:02.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:15:0)
[00:31:02.328] Timer tick, asking Buildbucket for the build status
[00:31:02.424] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:02.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:16:0) after 1m0s
[00:32:02.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:16:0)
[00:32:02.448] Timer tick, asking Buildbucket for the build status
[00:32:02.593] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:02.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:17:0) after 1m0s
[00:33:02.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:17:0)
[00:33:02.614] Timer tick, asking Buildbucket for the build status
[00:33:02.705] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:02.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:18:0) after 1m0s
[00:34:02.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:18:0)
[00:34:02.720] Timer tick, asking Buildbucket for the build status
[00:34:03.426] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:03.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:19:0) after 1m0s
[00:35:03.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:19:0)
[00:35:03.443] Timer tick, asking Buildbucket for the build status
[00:35:03.555] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:03.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:20:0) after 1m0s
[00:36:03.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:20:0)
[00:36:03.583] Timer tick, asking Buildbucket for the build status
[00:36:04.008] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:04.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:21:0) after 1m0s
[00:37:04.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:21:0)
[00:37:04.026] Timer tick, asking Buildbucket for the build status
[00:37:05.311] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:05.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:22:0) after 1m0s
[00:38:05.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:22:0)
[00:38:05.331] Timer tick, asking Buildbucket for the build status
[00:38:05.593] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:05.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:23:0) after 1m0s
[00:39:05.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:23:0)
[00:39:05.615] Timer tick, asking Buildbucket for the build status
[00:39:06.073] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:06.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:24:0) after 1m0s
[00:40:06.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:24:0)
[00:40:06.094] Timer tick, asking Buildbucket for the build status
[00:40:07.009] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:07.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:25:0) after 1m0s
[00:41:07.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:25:0)
[00:41:07.026] Timer tick, asking Buildbucket for the build status
[00:41:07.104] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:07.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:26:0) after 1m0s
[00:42:07.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:26:0)
[00:42:07.108] Timer tick, asking Buildbucket for the build status
[00:42:07.610] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:07.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:27:0) after 1m0s
[00:43:07.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:27:0)
[00:43:07.597] Timer tick, asking Buildbucket for the build status
[00:43:07.649] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:07.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:28:0) after 1m0s
[00:44:07.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:28:0)
[00:44:07.669] Timer tick, asking Buildbucket for the build status
[00:44:08.283] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:08.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:29:0) after 1m0s
[00:45:08.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:29:0)
[00:45:08.287] Timer tick, asking Buildbucket for the build status
[00:45:08.355] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:08.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:30:0) after 1m0s
[00:46:08.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:30:0)
[00:46:08.375] Timer tick, asking Buildbucket for the build status
[00:46:08.431] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:08.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:31:0) after 1m0s
[00:47:08.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:31:0)
[00:47:08.449] Timer tick, asking Buildbucket for the build status
[00:47:08.556] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:08.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:32:0) after 1m0s
[00:48:08.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:32:0)
[00:48:08.563] Timer tick, asking Buildbucket for the build status
[00:48:08.702] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:08.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:33:0) after 1m0s
[00:49:08.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:33:0)
[00:49:08.723] Timer tick, asking Buildbucket for the build status
[00:49:08.959] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:08.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:34:0) after 1m0s
[00:50:08.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:34:0)
[00:50:08.984] Timer tick, asking Buildbucket for the build status
[00:50:09.498] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:09.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:35:0) after 1m0s
[00:51:09.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:35:0)
[00:51:09.530] Timer tick, asking Buildbucket for the build status
[00:51:09.761] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:09.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:36:0) after 1m0s
[00:52:09.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:36:0)
[00:52:09.781] Timer tick, asking Buildbucket for the build status
[00:52:09.897] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:09.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:37:0) after 1m0s
[00:53:09.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:37:0)
[00:53:09.919] Timer tick, asking Buildbucket for the build status
[00:53:09.967] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:09.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:38:0) after 1m0s
[00:54:09.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:38:0)
[00:54:09.989] Timer tick, asking Buildbucket for the build status
[00:54:10.396] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:10.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:39:0) after 1m0s
[00:55:10.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:39:0)
[00:55:10.614] Timer tick, asking Buildbucket for the build status
[00:55:10.674] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:10.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:40:0) after 1m0s
[00:56:10.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:40:0)
[00:56:10.694] Timer tick, asking Buildbucket for the build status
[00:56:10.933] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:10.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:41:0) after 1m0s
[00:57:10.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:41:0)
[00:57:10.953] Timer tick, asking Buildbucket for the build status
[00:57:11.018] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:11.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:42:0) after 1m0s
[00:58:11.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:42:0)
[00:58:11.093] Timer tick, asking Buildbucket for the build status
[00:58:11.191] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:11.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:43:0) after 1m0s
[00:59:11.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:43:0)
[00:59:11.218] Timer tick, asking Buildbucket for the build status
[00:59:11.309] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:11.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:44:0) after 1m0s
[01:00:11.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:44:0)
[01:00:11.304] Timer tick, asking Buildbucket for the build status
[01:00:11.438] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:11.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:45:0) after 1m0s
[01:01:11.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:45:0)
[01:01:11.453] Timer tick, asking Buildbucket for the build status
[01:01:11.530] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:11.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:46:0) after 1m0s
[01:02:11.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:46:0)
[01:02:11.609] Timer tick, asking Buildbucket for the build status
[01:02:12.273] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:12.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:47:0) after 1m0s
[01:03:12.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:47:0)
[01:03:12.266] Timer tick, asking Buildbucket for the build status
[01:03:12.328] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:12.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:48:0) after 1m0s
[01:04:12.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:48:0)
[01:04:12.399] Timer tick, asking Buildbucket for the build status
[01:04:12.618] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:12.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:49:0) after 1m0s
[01:05:12.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:49:0)
[01:05:12.648] Timer tick, asking Buildbucket for the build status
[01:05:12.724] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:12.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:50:0) after 1m0s
[01:06:12.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:50:0)
[01:06:12.744] Timer tick, asking Buildbucket for the build status
[01:06:12.964] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:12.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:51:0) after 1m0s
[01:07:12.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:51:0)
[01:07:12.988] Timer tick, asking Buildbucket for the build status
[01:07:13.183] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:13.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:52:0) after 1m0s
[01:08:13.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:52:0)
[01:08:13.201] Timer tick, asking Buildbucket for the build status
[01:08:13.334] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:13.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:53:0) after 1m0s
[01:09:13.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:53:0)
[01:09:13.401] Timer tick, asking Buildbucket for the build status
[01:09:13.569] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:13.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:54:0) after 1m0s
[01:10:13.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:54:0)
[01:10:13.589] Timer tick, asking Buildbucket for the build status
[01:10:14.302] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:14.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:55:0) after 1m0s
[01:11:14.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:55:0)
[01:11:14.330] Timer tick, asking Buildbucket for the build status
[01:11:14.496] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:14.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:56:0) after 1m0s
[01:12:14.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:56:0)
[01:12:14.500] Timer tick, asking Buildbucket for the build status
[01:12:17.474] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:17.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:57:0) after 1m0s
[01:13:17.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:57:0)
[01:13:17.496] Timer tick, asking Buildbucket for the build status
[01:13:17.781] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:17.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:58:0) after 1m0s
[01:14:17.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:58:0)
[01:14:17.803] Timer tick, asking Buildbucket for the build status
[01:14:18.111] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:18.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:59:0) after 1m0s
[01:15:18.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:59:0)
[01:15:18.139] Timer tick, asking Buildbucket for the build status
[01:15:18.451] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:18.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:60:0) after 1m0s
[01:16:18.440] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:60:0)
[01:16:18.440] Timer tick, asking Buildbucket for the build status
[01:16:18.534] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:18.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:61:0) after 1m0s
[01:17:18.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:61:0)
[01:17:18.539] Timer tick, asking Buildbucket for the build status
[01:17:18.831] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:18.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:62:0) after 1m0s
[01:18:18.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:62:0)
[01:18:18.843] Timer tick, asking Buildbucket for the build status
[01:18:19.470] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:19.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:63:0) after 1m0s
[01:19:19.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:63:0)
[01:19:19.536] Timer tick, asking Buildbucket for the build status
[01:19:19.716] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:19.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:64:0) after 1m0s
[01:20:19.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:64:0)
[01:20:19.741] Timer tick, asking Buildbucket for the build status
[01:20:20.102] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:20.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:65:0) after 1m0s
[01:21:20.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:65:0)
[01:21:20.180] Timer tick, asking Buildbucket for the build status
[01:21:21.276] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:21.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:66:0) after 1m0s
[01:22:21.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:66:0)
[01:22:21.295] Timer tick, asking Buildbucket for the build status
[01:22:22.189] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:22.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:67:0) after 1m0s
[01:23:22.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:67:0)
[01:23:22.211] Timer tick, asking Buildbucket for the build status
[01:23:22.464] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:22.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:68:0) after 1m0s
[01:24:22.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:68:0)
[01:24:22.482] Timer tick, asking Buildbucket for the build status
[01:24:22.802] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:22.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:69:0) after 1m0s
[01:25:22.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:69:0)
[01:25:22.820] Timer tick, asking Buildbucket for the build status
[01:25:23.218] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:23.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:70:0) after 1m0s
[01:26:23.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:70:0)
[01:26:23.246] Timer tick, asking Buildbucket for the build status
[01:26:23.314] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:23.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:71:0) after 1m0s
[01:27:23.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:71:0)
[01:27:23.333] Timer tick, asking Buildbucket for the build status
[01:27:23.737] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:23.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:72:0) after 1m0s
[01:28:23.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:72:0)
[01:28:23.803] Timer tick, asking Buildbucket for the build status
[01:28:23.944] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:23.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:73:0) after 1m0s
[01:29:23.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:73:0)
[01:29:23.968] Timer tick, asking Buildbucket for the build status
[01:29:24.364] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:24.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:74:0) after 1m0s
[01:30:24.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:74:0)
[01:30:24.383] Timer tick, asking Buildbucket for the build status
[01:30:24.957] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:24.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:75:0) after 1m0s
[01:31:24.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:75:0)
[01:31:24.978] Timer tick, asking Buildbucket for the build status
[01:31:25.230] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:25.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:76:0) after 1m0s
[01:32:25.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:76:0)
[01:32:25.246] Timer tick, asking Buildbucket for the build status
[01:32:25.302] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:25.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:77:0) after 1m0s
[01:33:25.322] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:77:0)
[01:33:25.322] Timer tick, asking Buildbucket for the build status
[01:33:25.669] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:25.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:78:0) after 1m0s
[01:34:25.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:78:0)
[01:34:25.689] Timer tick, asking Buildbucket for the build status
[01:34:26.142] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:26.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:79:0) after 1m0s
[01:35:26.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:79:0)
[01:35:26.140] Timer tick, asking Buildbucket for the build status
[01:35:26.328] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:26.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:80:0) after 1m0s
[01:36:26.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:80:0)
[01:36:26.360] Timer tick, asking Buildbucket for the build status
[01:36:26.697] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:26.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:81:0) after 1m0s
[01:37:26.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:81:0)
[01:37:26.706] Timer tick, asking Buildbucket for the build status
[01:37:27.196] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:27.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:82:0) after 1m0s
[01:38:27.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:82:0)
[01:38:27.207] Timer tick, asking Buildbucket for the build status
[01:38:27.360] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:27.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:83:0) after 1m0s
[01:39:27.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:83:0)
[01:39:27.417] Timer tick, asking Buildbucket for the build status
[01:39:27.675] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:27.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:84:0) after 1m0s
[01:40:27.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:84:0)
[01:40:27.703] Timer tick, asking Buildbucket for the build status
[01:40:27.815] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:27.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:85:0) after 1m0s
[01:41:27.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:85:0)
[01:41:27.850] Timer tick, asking Buildbucket for the build status
[01:41:27.986] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:27.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:86:0) after 1m0s
[01:42:27.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:86:0)
[01:42:27.994] Timer tick, asking Buildbucket for the build status
[01:42:28.347] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:28.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:87:0) after 1m0s
[01:43:28.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:87:0)
[01:43:28.372] Timer tick, asking Buildbucket for the build status
[01:43:28.613] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:28.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:88:0) after 1m0s
[01:44:28.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:88:0)
[01:44:28.647] Timer tick, asking Buildbucket for the build status
[01:44:29.003] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:29.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:89:0) after 1m0s
[01:45:29.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:89:0)
[01:45:29.026] Timer tick, asking Buildbucket for the build status
[01:45:29.323] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:29.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:90:0) after 1m0s
[01:46:29.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:90:0)
[01:46:29.325] Timer tick, asking Buildbucket for the build status
[01:46:30.217] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:30.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:91:0) after 1m0s
[01:47:30.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:91:0)
[01:47:30.299] Timer tick, asking Buildbucket for the build status
[01:47:30.750] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:30.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:92:0) after 1m0s
[01:48:30.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:92:0)
[01:48:30.802] Timer tick, asking Buildbucket for the build status
[01:48:30.888] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:30.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:93:0) after 1m0s
[01:49:30.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:93:0)
[01:49:30.914] Timer tick, asking Buildbucket for the build status
[01:49:31.021] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:31.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:94:0) after 1m0s
[01:50:31.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:94:0)
[01:50:31.048] Timer tick, asking Buildbucket for the build status
[01:50:31.155] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:31.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:95:0) after 1m0s
[01:51:31.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:95:0)
[01:51:31.144] Timer tick, asking Buildbucket for the build status
[01:51:31.202] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:31.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:96:0) after 1m0s
[01:52:31.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:96:0)
[01:52:31.209] Timer tick, asking Buildbucket for the build status
[01:52:31.297] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:31.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:97:0) after 1m0s
[01:53:31.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:97:0)
[01:53:31.315] Timer tick, asking Buildbucket for the build status
[01:53:31.501] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:31.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:98:0) after 1m0s
[01:54:31.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:98:0)
[01:54:31.592] Timer tick, asking Buildbucket for the build status
[01:54:32.137] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:32.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:99:0) after 1m0s
[01:55:32.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:99:0)
[01:55:32.131] Timer tick, asking Buildbucket for the build status
[01:55:32.365] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:32.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:100:0) after 1m0s
[01:56:32.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:100:0)
[01:56:32.387] Timer tick, asking Buildbucket for the build status
[01:56:32.779] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:32.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:101:0) after 1m0s
[01:57:32.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:101:0)
[01:57:32.824] Timer tick, asking Buildbucket for the build status
[01:57:33.388] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:33.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:102:0) after 1m0s
[01:58:33.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:102:0)
[01:58:33.441] Timer tick, asking Buildbucket for the build status
[01:58:33.516] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:33.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:103:0) after 1m0s
[01:59:33.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:103:0)
[01:59:33.542] Timer tick, asking Buildbucket for the build status
[01:59:34.330] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:34.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:104:0) after 1m0s
[02:00:34.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:104:0)
[02:00:34.351] Timer tick, asking Buildbucket for the build status
[02:00:34.497] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:34.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:105:0) after 1m0s
[02:01:34.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:105:0)
[02:01:34.516] Timer tick, asking Buildbucket for the build status
[02:01:35.173] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:35.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:106:0) after 1m0s
[02:02:35.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:106:0)
[02:02:35.208] Timer tick, asking Buildbucket for the build status
[02:02:35.319] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:35.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:107:0) after 1m0s
[02:03:35.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:107:0)
[02:03:35.360] Timer tick, asking Buildbucket for the build status
[02:03:35.599] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:35.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:108:0) after 1m0s
[02:04:35.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:108:0)
[02:04:35.620] Timer tick, asking Buildbucket for the build status
[02:04:36.214] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:36.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:109:0) after 1m0s
[02:05:36.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:109:0)
[02:05:36.218] Timer tick, asking Buildbucket for the build status
[02:05:36.341] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:36.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:110:0) after 1m0s
[02:06:36.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:110:0)
[02:06:36.320] Timer tick, asking Buildbucket for the build status
[02:06:36.622] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:36.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:111:0) after 1m0s
[02:07:36.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:111:0)
[02:07:36.642] Timer tick, asking Buildbucket for the build status
[02:07:36.694] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:36.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:112:0) after 1m0s
[02:08:36.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:112:0)
[02:08:36.734] Timer tick, asking Buildbucket for the build status
[02:08:36.879] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:36.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:113:0) after 1m0s
[02:09:36.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:113:0)
[02:09:36.918] Timer tick, asking Buildbucket for the build status
[02:09:37.039] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:37.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:114:0) after 1m0s
[02:10:37.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:114:0)
[02:10:37.063] Timer tick, asking Buildbucket for the build status
[02:10:37.701] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:37.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:115:0) after 1m0s
[02:11:37.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:115:0)
[02:11:37.721] Timer tick, asking Buildbucket for the build status
[02:11:38.225] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:38.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:116:0) after 1m0s
[02:12:38.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:116:0)
[02:12:38.246] Timer tick, asking Buildbucket for the build status
[02:12:38.383] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:38.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:117:0) after 1m0s
[02:13:38.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:117:0)
[02:13:38.407] Timer tick, asking Buildbucket for the build status
[02:13:38.450] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:38.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:118:0) after 1m0s
[02:14:38.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:118:0)
[02:14:38.471] Timer tick, asking Buildbucket for the build status
[02:14:39.013] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:39.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:119:0) after 1m0s
[02:15:38.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:119:0)
[02:15:38.986] Timer tick, asking Buildbucket for the build status
[02:15:39.254] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:39.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:120:0) after 1m0s
[02:16:39.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:120:0)
[02:16:39.278] Timer tick, asking Buildbucket for the build status
[02:16:39.967] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:39.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:121:0) after 1m0s
[02:17:39.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:121:0)
[02:17:39.988] Timer tick, asking Buildbucket for the build status
[02:17:40.084] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:40.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:122:0) after 1m0s
[02:18:40.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:122:0)
[02:18:40.106] Timer tick, asking Buildbucket for the build status
[02:18:40.195] Build 8914435251705584448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:40.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079865313730501616:123:0) after 1m0s
[02:19:00.078] Received PubSub notification, asking Buildbucket for the build status
[02:19:00.730] Build 8914435251705584448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:19:00.730] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556936338855178",
"created_by": "project:fuchsia",
"created_ts": "1556929077389506",
"failure_reason": "BUILD_FAILURE",
"id": "8914435251705584448",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f\"}}",
"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-tm1b\", \"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\": \"5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f\", \"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-003729e9-us-central1-c-tm1b\"], \"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": "1556929109384355",
"status": "COMPLETED",
"status_changed_ts": "1556929111683696",
"tags": [
"builder:clang",
"buildset:commit/git/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5fe2ddbdf47dddc90233e3240a3aa6bcdea8721f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079865313730501616",
"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/8914435251705584448/+/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:449905f14c540510",
"user_agent:luci-scheduler"
],
"updated_ts": "1556936339206445",
"url": "https://ci.chromium.org/b/8914435251705584448",
"utcnow_ts": "1556936340711160"
}
}
[02:19:00.730] Invocation finished in 2h1m4.655108333s with status FAILED