[16:25:48.188] New invocation is queued and will start shortly
[16:25:49.323] Starting the invocation (attempt 1)
[16:25:49.397] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:25:49.398] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:25:49.398] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081344569186067584",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081344569186067584",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"gitiles_ref:refs/heads/master"
]
}
[16:25:50.372] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555518349500436",
"id": "8915914507113075424",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555518349946511",
"tags": [
"builder:clang",
"buildset:commit/git/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081344569186067584",
"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/8915914507113075424/+/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": "1555518349946630",
"url": "https://ci.chromium.org/b/8915914507113075424",
"utcnow_ts": "1555518350359792"
}
}
[16:25:50.372] Task URL: https://ci.chromium.org/b/8915914507113075424
[16:25:50.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:2:0) after 1m0s
[16:26:20.670] Received PubSub notification, asking Buildbucket for the build status
[16:26:20.721] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:50.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:2:0)
[16:26:50.449] Timer tick, asking Buildbucket for the build status
[16:26:50.757] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:50.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:4:0) after 1m0s
[16:27:50.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:4:0)
[16:27:50.795] Timer tick, asking Buildbucket for the build status
[16:27:51.084] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:51.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:5:0) after 1m0s
[16:28:51.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:5:0)
[16:28:51.106] Timer tick, asking Buildbucket for the build status
[16:28:51.291] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:51.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:6:0) after 1m0s
[16:29:51.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:6:0)
[16:29:51.298] Timer tick, asking Buildbucket for the build status
[16:29:51.372] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:51.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:7:0) after 1m0s
[16:30:51.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:7:0)
[16:30:51.433] Timer tick, asking Buildbucket for the build status
[16:30:51.591] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:51.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:8:0) after 1m0s
[16:31:51.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:8:0)
[16:31:51.701] Timer tick, asking Buildbucket for the build status
[16:31:51.984] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:51.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:9:0) after 1m0s
[16:32:52.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:9:0)
[16:32:52.004] Timer tick, asking Buildbucket for the build status
[16:32:52.047] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:52.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:10:0) after 1m0s
[16:33:52.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:10:0)
[16:33:52.074] Timer tick, asking Buildbucket for the build status
[16:33:52.317] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:52.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:11:0) after 1m0s
[16:34:52.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:11:0)
[16:34:52.353] Timer tick, asking Buildbucket for the build status
[16:34:52.400] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:52.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:12:0) after 1m0s
[16:35:52.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:12:0)
[16:35:52.394] Timer tick, asking Buildbucket for the build status
[16:35:52.592] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:52.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:13:0) after 1m0s
[16:36:52.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:13:0)
[16:36:52.618] Timer tick, asking Buildbucket for the build status
[16:36:52.659] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:52.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:14:0) after 1m0s
[16:37:52.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:14:0)
[16:37:52.686] Timer tick, asking Buildbucket for the build status
[16:37:52.765] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:52.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:15:0) after 1m0s
[16:38:52.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:15:0)
[16:38:52.812] Timer tick, asking Buildbucket for the build status
[16:38:52.978] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:52.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:16:0) after 1m0s
[16:39:53.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:16:0)
[16:39:53.077] Timer tick, asking Buildbucket for the build status
[16:39:53.135] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:53.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:17:0) after 1m0s
[16:40:53.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:17:0)
[16:40:53.155] Timer tick, asking Buildbucket for the build status
[16:40:53.197] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:53.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:18:0) after 1m0s
[16:41:53.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:18:0)
[16:41:53.217] Timer tick, asking Buildbucket for the build status
[16:41:53.419] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:53.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:19:0) after 1m0s
[16:42:53.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:19:0)
[16:42:53.438] Timer tick, asking Buildbucket for the build status
[16:42:53.491] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:53.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:20:0) after 1m0s
[16:43:53.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:20:0)
[16:43:53.572] Timer tick, asking Buildbucket for the build status
[16:43:53.705] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:53.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:21:0) after 1m0s
[16:44:53.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:21:0)
[16:44:53.726] Timer tick, asking Buildbucket for the build status
[16:44:53.860] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:53.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:22:0) after 1m0s
[16:45:53.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:22:0)
[16:45:53.877] Timer tick, asking Buildbucket for the build status
[16:45:53.921] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:53.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:23:0) after 1m0s
[16:46:53.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:23:0)
[16:46:53.936] Timer tick, asking Buildbucket for the build status
[16:46:54.060] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:54.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:24:0) after 1m0s
[16:47:54.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:24:0)
[16:47:54.067] Timer tick, asking Buildbucket for the build status
[16:47:54.255] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:54.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:25:0) after 1m0s
[16:48:54.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:25:0)
[16:48:54.273] Timer tick, asking Buildbucket for the build status
[16:48:54.337] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:54.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:26:0) after 1m0s
[16:49:54.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:26:0)
[16:49:54.334] Timer tick, asking Buildbucket for the build status
[16:49:54.416] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:54.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:27:0) after 1m0s
[16:50:54.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:27:0)
[16:50:54.404] Timer tick, asking Buildbucket for the build status
[16:50:54.458] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:54.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:28:0) after 1m0s
[16:51:54.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:28:0)
[16:51:54.434] Timer tick, asking Buildbucket for the build status
[16:51:54.586] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:54.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:29:0) after 1m0s
[16:52:54.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:29:0)
[16:52:54.602] Timer tick, asking Buildbucket for the build status
[16:52:54.645] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:54.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:30:0) after 1m0s
[16:53:54.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:30:0)
[16:53:54.670] Timer tick, asking Buildbucket for the build status
[16:53:54.975] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:54.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:31:0) after 1m0s
[16:54:55.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:31:0)
[16:54:55.008] Timer tick, asking Buildbucket for the build status
[16:54:55.056] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:55.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:32:0) after 1m0s
[16:55:55.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:32:0)
[16:55:55.077] Timer tick, asking Buildbucket for the build status
[16:55:55.181] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:55.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:33:0) after 1m0s
[16:56:55.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:33:0)
[16:56:55.224] Timer tick, asking Buildbucket for the build status
[16:56:55.328] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:55.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:34:0) after 1m0s
[16:57:55.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:34:0)
[16:57:55.347] Timer tick, asking Buildbucket for the build status
[16:57:55.404] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:55.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:35:0) after 1m0s
[16:58:55.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:35:0)
[16:58:55.427] Timer tick, asking Buildbucket for the build status
[16:58:55.476] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:55.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:36:0) after 1m0s
[16:59:55.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:36:0)
[16:59:55.476] Timer tick, asking Buildbucket for the build status
[16:59:55.521] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:55.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:37:0) after 1m0s
[17:00:55.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:37:0)
[17:00:55.542] Timer tick, asking Buildbucket for the build status
[17:00:55.604] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:55.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:38:0) after 1m0s
[17:01:55.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:38:0)
[17:01:55.630] Timer tick, asking Buildbucket for the build status
[17:01:55.679] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:55.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:39:0) after 1m0s
[17:02:55.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:39:0)
[17:02:55.674] Timer tick, asking Buildbucket for the build status
[17:02:56.069] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:56.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:40:0) after 1m0s
[17:03:56.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:40:0)
[17:03:56.089] Timer tick, asking Buildbucket for the build status
[17:03:56.138] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:56.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:41:0) after 1m0s
[17:04:56.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:41:0)
[17:04:56.171] Timer tick, asking Buildbucket for the build status
[17:04:56.272] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:56.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:42:0) after 1m0s
[17:05:56.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:42:0)
[17:05:56.294] Timer tick, asking Buildbucket for the build status
[17:05:56.428] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:56.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:43:0) after 1m0s
[17:06:56.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:43:0)
[17:06:56.449] Timer tick, asking Buildbucket for the build status
[17:06:56.495] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:56.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:44:0) after 1m0s
[17:07:56.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:44:0)
[17:07:56.493] Timer tick, asking Buildbucket for the build status
[17:07:56.712] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:56.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:45:0) after 1m0s
[17:08:56.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:45:0)
[17:08:56.737] Timer tick, asking Buildbucket for the build status
[17:08:56.800] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:56.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:46:0) after 1m0s
[17:09:56.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:46:0)
[17:09:56.821] Timer tick, asking Buildbucket for the build status
[17:09:56.866] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:56.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:47:0) after 1m0s
[17:10:56.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:47:0)
[17:10:56.884] Timer tick, asking Buildbucket for the build status
[17:10:56.946] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:56.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:48:0) after 1m0s
[17:11:56.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:48:0)
[17:11:56.923] Timer tick, asking Buildbucket for the build status
[17:11:57.015] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:57.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:49:0) after 1m0s
[17:12:57.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:49:0)
[17:12:57.012] Timer tick, asking Buildbucket for the build status
[17:12:57.057] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:57.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:50:0) after 1m0s
[17:13:57.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:50:0)
[17:13:57.079] Timer tick, asking Buildbucket for the build status
[17:13:57.260] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:57.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:51:0) after 1m0s
[17:14:57.229] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:51:0)
[17:14:57.229] Timer tick, asking Buildbucket for the build status
[17:14:57.285] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:57.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:52:0) after 1m0s
[17:15:57.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:52:0)
[17:15:57.306] Timer tick, asking Buildbucket for the build status
[17:15:57.478] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:57.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:53:0) after 1m0s
[17:16:57.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:53:0)
[17:16:57.499] Timer tick, asking Buildbucket for the build status
[17:16:57.581] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:57.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:54:0) after 1m0s
[17:17:57.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:54:0)
[17:17:57.598] Timer tick, asking Buildbucket for the build status
[17:17:57.771] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:57.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:55:0) after 1m0s
[17:18:57.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:55:0)
[17:18:57.792] Timer tick, asking Buildbucket for the build status
[17:18:57.839] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:57.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:56:0) after 1m0s
[17:19:57.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:56:0)
[17:19:57.865] Timer tick, asking Buildbucket for the build status
[17:19:57.907] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:57.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:57:0) after 1m0s
[17:20:57.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:57:0)
[17:20:57.912] Timer tick, asking Buildbucket for the build status
[17:20:57.983] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:57.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:58:0) after 1m0s
[17:21:57.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:58:0)
[17:21:57.967] Timer tick, asking Buildbucket for the build status
[17:21:58.107] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:59:0) after 1m0s
[17:22:58.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:59:0)
[17:22:58.131] Timer tick, asking Buildbucket for the build status
[17:22:58.282] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:60:0) after 1m0s
[17:23:58.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:60:0)
[17:23:58.317] Timer tick, asking Buildbucket for the build status
[17:23:58.416] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:58.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:61:0) after 1m0s
[17:24:58.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:61:0)
[17:24:58.403] Timer tick, asking Buildbucket for the build status
[17:24:58.586] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:62:0) after 1m0s
[17:25:58.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:62:0)
[17:25:58.618] Timer tick, asking Buildbucket for the build status
[17:25:58.693] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:63:0) after 1m0s
[17:26:58.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:63:0)
[17:26:58.710] Timer tick, asking Buildbucket for the build status
[17:26:58.789] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:58.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:64:0) after 1m0s
[17:27:58.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:64:0)
[17:27:58.773] Timer tick, asking Buildbucket for the build status
[17:27:59.019] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:65:0) after 1m0s
[17:28:59.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:65:0)
[17:28:59.036] Timer tick, asking Buildbucket for the build status
[17:28:59.210] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:66:0) after 1m0s
[17:29:59.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:66:0)
[17:29:59.233] Timer tick, asking Buildbucket for the build status
[17:29:59.412] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:59.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:67:0) after 1m0s
[17:30:59.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:67:0)
[17:30:59.425] Timer tick, asking Buildbucket for the build status
[17:30:59.494] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:59.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:68:0) after 1m0s
[17:31:59.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:68:0)
[17:31:59.505] Timer tick, asking Buildbucket for the build status
[17:31:59.586] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:59.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:69:0) after 1m0s
[17:32:59.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:69:0)
[17:32:59.576] Timer tick, asking Buildbucket for the build status
[17:32:59.658] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:59.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:70:0) after 1m0s
[17:33:59.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:70:0)
[17:33:59.653] Timer tick, asking Buildbucket for the build status
[17:33:59.725] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:59.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:71:0) after 1m0s
[17:34:59.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:71:0)
[17:34:59.714] Timer tick, asking Buildbucket for the build status
[17:34:59.778] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:59.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:72:0) after 1m0s
[17:35:59.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:72:0)
[17:35:59.800] Timer tick, asking Buildbucket for the build status
[17:35:59.913] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:59.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:73:0) after 1m0s
[17:36:59.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:73:0)
[17:36:59.939] Timer tick, asking Buildbucket for the build status
[17:37:00.281] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:00.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:74:0) after 1m0s
[17:38:00.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:74:0)
[17:38:00.281] Timer tick, asking Buildbucket for the build status
[17:38:00.372] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:00.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:75:0) after 1m0s
[17:39:00.440] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:75:0)
[17:39:00.440] Timer tick, asking Buildbucket for the build status
[17:39:00.584] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:00.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:76:0) after 1m0s
[17:40:00.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:76:0)
[17:40:00.616] Timer tick, asking Buildbucket for the build status
[17:40:00.668] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:00.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:77:0) after 1m0s
[17:41:00.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:77:0)
[17:41:00.702] Timer tick, asking Buildbucket for the build status
[17:41:00.787] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:00.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:78:0) after 1m0s
[17:42:00.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:78:0)
[17:42:00.772] Timer tick, asking Buildbucket for the build status
[17:42:00.857] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:00.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:79:0) after 1m0s
[17:43:00.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:79:0)
[17:43:00.886] Timer tick, asking Buildbucket for the build status
[17:43:00.930] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:00.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:80:0) after 1m0s
[17:44:00.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:80:0)
[17:44:00.921] Timer tick, asking Buildbucket for the build status
[17:44:01.089] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:01.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:81:0) after 1m0s
[17:45:01.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:81:0)
[17:45:01.114] Timer tick, asking Buildbucket for the build status
[17:45:01.221] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:01.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:82:0) after 1m0s
[17:46:01.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:82:0)
[17:46:01.232] Timer tick, asking Buildbucket for the build status
[17:46:01.281] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:01.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:83:0) after 1m0s
[17:47:01.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:83:0)
[17:47:01.294] Timer tick, asking Buildbucket for the build status
[17:47:01.518] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:01.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:84:0) after 1m0s
[17:48:01.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:84:0)
[17:48:01.556] Timer tick, asking Buildbucket for the build status
[17:48:01.626] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:01.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:85:0) after 1m0s
[17:49:01.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:85:0)
[17:49:01.664] Timer tick, asking Buildbucket for the build status
[17:49:01.814] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:01.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:86:0) after 1m0s
[17:50:01.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:86:0)
[17:50:01.829] Timer tick, asking Buildbucket for the build status
[17:50:01.883] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:01.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:87:0) after 1m0s
[17:51:01.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:87:0)
[17:51:01.914] Timer tick, asking Buildbucket for the build status
[17:51:01.973] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:01.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:88:0) after 1m0s
[17:52:01.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:88:0)
[17:52:01.996] Timer tick, asking Buildbucket for the build status
[17:52:02.050] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:02.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:89:0) after 1m0s
[17:53:02.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:89:0)
[17:53:02.075] Timer tick, asking Buildbucket for the build status
[17:53:02.327] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:02.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:90:0) after 1m0s
[17:54:02.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:90:0)
[17:54:02.346] Timer tick, asking Buildbucket for the build status
[17:54:02.384] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:02.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:91:0) after 1m0s
[17:55:02.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:91:0)
[17:55:02.459] Timer tick, asking Buildbucket for the build status
[17:55:02.527] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:02.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:92:0) after 1m0s
[17:56:02.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:92:0)
[17:56:02.547] Timer tick, asking Buildbucket for the build status
[17:56:02.969] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:02.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:93:0) after 1m0s
[17:57:02.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:93:0)
[17:57:02.993] Timer tick, asking Buildbucket for the build status
[17:57:03.063] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:03.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:94:0) after 1m0s
[17:58:03.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:94:0)
[17:58:03.093] Timer tick, asking Buildbucket for the build status
[17:58:03.154] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:03.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:95:0) after 1m0s
[17:59:03.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:95:0)
[17:59:03.177] Timer tick, asking Buildbucket for the build status
[17:59:03.218] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:03.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:96:0) after 1m0s
[18:00:03.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:96:0)
[18:00:03.258] Timer tick, asking Buildbucket for the build status
[18:00:03.448] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:03.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:97:0) after 1m0s
[18:01:03.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:97:0)
[18:01:03.469] Timer tick, asking Buildbucket for the build status
[18:01:03.598] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:03.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:98:0) after 1m0s
[18:02:03.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:98:0)
[18:02:03.649] Timer tick, asking Buildbucket for the build status
[18:02:03.794] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:03.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:99:0) after 1m0s
[18:03:03.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:99:0)
[18:03:03.815] Timer tick, asking Buildbucket for the build status
[18:03:04.031] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:04.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:100:0) after 1m0s
[18:04:04.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:100:0)
[18:04:04.153] Timer tick, asking Buildbucket for the build status
[18:04:05.698] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:05.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:101:0) after 1m0s
[18:05:05.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:101:0)
[18:05:05.688] Timer tick, asking Buildbucket for the build status
[18:05:05.807] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:05.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:102:0) after 1m0s
[18:06:05.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:102:0)
[18:06:05.832] Timer tick, asking Buildbucket for the build status
[18:06:05.990] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:05.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:103:0) after 1m0s
[18:07:06.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:103:0)
[18:07:06.076] Timer tick, asking Buildbucket for the build status
[18:07:06.272] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:06.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:104:0) after 1m0s
[18:08:06.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:104:0)
[18:08:06.277] Timer tick, asking Buildbucket for the build status
[18:08:08.857] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:08.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:105:0) after 1m0s
[18:09:08.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:105:0)
[18:09:08.842] Timer tick, asking Buildbucket for the build status
[18:09:09.285] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:09.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:106:0) after 1m0s
[18:10:09.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:106:0)
[18:10:09.324] Timer tick, asking Buildbucket for the build status
[18:10:09.360] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:09.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:107:0) after 1m0s
[18:11:09.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:107:0)
[18:11:09.387] Timer tick, asking Buildbucket for the build status
[18:11:09.552] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:09.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:108:0) after 1m0s
[18:12:09.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:108:0)
[18:12:09.573] Timer tick, asking Buildbucket for the build status
[18:12:09.620] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:09.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:109:0) after 1m0s
[18:13:09.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:109:0)
[18:13:09.603] Timer tick, asking Buildbucket for the build status
[18:13:09.738] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:09.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:110:0) after 1m0s
[18:14:09.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:110:0)
[18:14:09.828] Timer tick, asking Buildbucket for the build status
[18:14:09.893] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:09.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:111:0) after 1m0s
[18:15:09.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:111:0)
[18:15:09.914] Timer tick, asking Buildbucket for the build status
[18:15:09.955] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:09.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:112:0) after 1m0s
[18:16:09.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:112:0)
[18:16:09.941] Timer tick, asking Buildbucket for the build status
[18:16:09.983] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:09.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:113:0) after 1m0s
[18:17:10.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:113:0)
[18:17:10.006] Timer tick, asking Buildbucket for the build status
[18:17:10.050] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:10.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:114:0) after 1m0s
[18:18:10.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:114:0)
[18:18:10.076] Timer tick, asking Buildbucket for the build status
[18:18:10.190] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:10.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:115:0) after 1m0s
[18:19:10.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:115:0)
[18:19:10.194] Timer tick, asking Buildbucket for the build status
[18:19:10.380] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:10.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:116:0) after 1m0s
[18:20:10.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:116:0)
[18:20:10.409] Timer tick, asking Buildbucket for the build status
[18:20:10.472] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:10.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:117:0) after 1m0s
[18:21:10.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:117:0)
[18:21:10.482] Timer tick, asking Buildbucket for the build status
[18:21:10.524] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:10.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:118:0) after 1m0s
[18:22:10.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:118:0)
[18:22:10.561] Timer tick, asking Buildbucket for the build status
[18:22:10.601] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:10.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:119:0) after 1m0s
[18:23:10.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:119:0)
[18:23:10.594] Timer tick, asking Buildbucket for the build status
[18:23:10.641] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:10.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:120:0) after 1m0s
[18:24:10.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:120:0)
[18:24:10.670] Timer tick, asking Buildbucket for the build status
[18:24:10.817] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:10.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:121:0) after 1m0s
[18:25:10.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:121:0)
[18:25:10.815] Timer tick, asking Buildbucket for the build status
[18:25:10.880] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:10.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:122:0) after 1m0s
[18:26:10.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:122:0)
[18:26:10.916] Timer tick, asking Buildbucket for the build status
[18:26:10.956] Build 8915914507113075424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:10.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081344569186067584:123:0) after 1m0s
[18:26:52.306] Received PubSub notification, asking Buildbucket for the build status
[18:26:52.482] Build 8915914507113075424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:26:52.482] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555525612082842",
"created_by": "project:fuchsia",
"created_ts": "1555518349500436",
"failure_reason": "BUILD_FAILURE",
"id": "8915914507113075424",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-jtgj\", \"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\": \"00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-jtgj\"], \"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\": [\"4260-fb32526\"], \"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": "1555518379460783",
"status": "COMPLETED",
"status_changed_ts": "1555518380042345",
"tags": [
"builder:clang",
"buildset:commit/git/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/00f2ab1c2f014ffc790c59fc0e9fa1bc8647cba2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081344569186067584",
"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/8915914507113075424/+/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:4444efed032c4f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555525612133667",
"url": "https://ci.chromium.org/b/8915914507113075424",
"utcnow_ts": "1555525612463406"
}
}
[18:26:52.482] Invocation finished in 2h1m4.343634807s with status FAILED