[20:51:48.093] New invocation is queued and will start shortly
[20:51:49.297] Starting the invocation (attempt 1)
[20:51:49.352] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:51:49.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:51:49.352] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078609924989499872",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"182c638fe090803a9e8ad2b357bb1df9500ae7af\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078609924989499872",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"gitiles_ref:refs/heads/master"
]
}
[20:51:52.215] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558126309821507",
"id": "8913179862511044240",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"182c638fe090803a9e8ad2b357bb1df9500ae7af\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558126311702290",
"tags": [
"builder:clang",
"buildset:commit/git/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078609924989499872",
"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/8913179862511044240/+/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": "1558126311702390",
"url": "https://ci.chromium.org/b/8913179862511044240",
"utcnow_ts": "1558126312107007"
}
}
[20:51:52.215] Task URL: https://ci.chromium.org/b/8913179862511044240
[20:51:52.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:2:0) after 1m0s
[20:52:00.928] Received PubSub notification, asking Buildbucket for the build status
[20:52:01.130] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:52.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:2:0)
[20:52:52.233] Timer tick, asking Buildbucket for the build status
[20:52:52.728] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:52.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:4:0) after 1m0s
[20:53:52.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:4:0)
[20:53:52.791] Timer tick, asking Buildbucket for the build status
[20:53:52.853] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:52.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:5:0) after 1m0s
[20:54:52.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:5:0)
[20:54:52.875] Timer tick, asking Buildbucket for the build status
[20:54:52.922] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:52.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:6:0) after 1m0s
[20:55:52.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:6:0)
[20:55:52.906] Timer tick, asking Buildbucket for the build status
[20:55:53.042] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:53.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:7:0) after 1m0s
[20:56:53.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:7:0)
[20:56:53.071] Timer tick, asking Buildbucket for the build status
[20:56:53.207] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:53.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:8:0) after 1m0s
[20:57:53.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:8:0)
[20:57:53.228] Timer tick, asking Buildbucket for the build status
[20:57:53.492] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:53.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:9:0) after 1m0s
[20:58:53.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:9:0)
[20:58:53.484] Timer tick, asking Buildbucket for the build status
[20:58:53.566] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:53.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:10:0) after 1m0s
[20:59:53.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:10:0)
[20:59:53.617] Timer tick, asking Buildbucket for the build status
[20:59:53.858] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:53.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:11:0) after 1m0s
[21:00:53.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:11:0)
[21:00:53.866] Timer tick, asking Buildbucket for the build status
[21:00:53.920] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:53.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:12:0) after 1m0s
[21:01:53.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:12:0)
[21:01:53.953] Timer tick, asking Buildbucket for the build status
[21:01:53.996] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:53.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:13:0) after 1m0s
[21:02:54.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:13:0)
[21:02:54.004] Timer tick, asking Buildbucket for the build status
[21:02:54.199] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:54.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:14:0) after 1m0s
[21:03:54.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:14:0)
[21:03:54.225] Timer tick, asking Buildbucket for the build status
[21:03:54.469] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:54.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:15:0) after 1m0s
[21:04:54.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:15:0)
[21:04:54.558] Timer tick, asking Buildbucket for the build status
[21:04:54.811] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:54.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:16:0) after 1m0s
[21:05:54.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:16:0)
[21:05:54.826] Timer tick, asking Buildbucket for the build status
[21:05:55.037] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:55.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:17:0) after 1m0s
[21:06:55.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:17:0)
[21:06:55.074] Timer tick, asking Buildbucket for the build status
[21:06:55.608] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:55.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:18:0) after 1m0s
[21:07:55.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:18:0)
[21:07:55.644] Timer tick, asking Buildbucket for the build status
[21:07:55.688] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:55.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:19:0) after 1m0s
[21:08:55.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:19:0)
[21:08:55.716] Timer tick, asking Buildbucket for the build status
[21:08:55.849] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:55.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:20:0) after 1m0s
[21:09:55.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:20:0)
[21:09:55.892] Timer tick, asking Buildbucket for the build status
[21:09:55.983] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:55.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:21:0) after 1m0s
[21:10:56.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:21:0)
[21:10:56.054] Timer tick, asking Buildbucket for the build status
[21:10:56.101] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:56.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:22:0) after 1m0s
[21:11:56.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:22:0)
[21:11:56.136] Timer tick, asking Buildbucket for the build status
[21:11:56.193] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:56.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:23:0) after 1m0s
[21:12:56.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:23:0)
[21:12:56.303] Timer tick, asking Buildbucket for the build status
[21:12:56.960] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:56.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:24:0) after 1m0s
[21:13:57.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:24:0)
[21:13:57.005] Timer tick, asking Buildbucket for the build status
[21:13:57.301] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:57.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:25:0) after 1m0s
[21:14:57.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:25:0)
[21:14:57.327] Timer tick, asking Buildbucket for the build status
[21:14:57.394] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:57.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:26:0) after 1m0s
[21:15:57.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:26:0)
[21:15:57.428] Timer tick, asking Buildbucket for the build status
[21:15:57.517] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:57.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:27:0) after 1m0s
[21:16:57.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:27:0)
[21:16:57.506] Timer tick, asking Buildbucket for the build status
[21:16:57.590] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:57.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:28:0) after 1m0s
[21:17:57.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:28:0)
[21:17:57.720] Timer tick, asking Buildbucket for the build status
[21:17:57.806] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:57.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:29:0) after 1m0s
[21:18:57.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:29:0)
[21:18:57.788] Timer tick, asking Buildbucket for the build status
[21:18:58.401] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:58.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:30:0) after 1m0s
[21:19:58.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:30:0)
[21:19:58.507] Timer tick, asking Buildbucket for the build status
[21:19:58.553] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:58.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:31:0) after 1m0s
[21:20:58.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:31:0)
[21:20:58.527] Timer tick, asking Buildbucket for the build status
[21:20:58.568] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:58.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:32:0) after 1m0s
[21:21:58.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:32:0)
[21:21:58.627] Timer tick, asking Buildbucket for the build status
[21:21:58.708] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:58.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:33:0) after 1m0s
[21:22:58.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:33:0)
[21:22:58.762] Timer tick, asking Buildbucket for the build status
[21:22:58.925] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:58.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:34:0) after 1m0s
[21:23:58.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:34:0)
[21:23:58.980] Timer tick, asking Buildbucket for the build status
[21:23:59.034] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:59.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:35:0) after 1m0s
[21:24:59.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:35:0)
[21:24:59.056] Timer tick, asking Buildbucket for the build status
[21:24:59.166] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:59.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:36:0) after 1m0s
[21:25:59.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:36:0)
[21:25:59.204] Timer tick, asking Buildbucket for the build status
[21:25:59.407] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:59.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:37:0) after 1m0s
[21:26:59.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:37:0)
[21:26:59.514] Timer tick, asking Buildbucket for the build status
[21:26:59.990] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:59.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:38:0) after 1m0s
[21:28:00.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:38:0)
[21:28:00.027] Timer tick, asking Buildbucket for the build status
[21:28:00.355] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:00.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:39:0) after 1m0s
[21:29:00.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:39:0)
[21:29:00.812] Timer tick, asking Buildbucket for the build status
[21:29:00.989] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:00.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:40:0) after 1m0s
[21:30:01.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:40:0)
[21:30:01.077] Timer tick, asking Buildbucket for the build status
[21:30:01.485] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:01.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:41:0) after 1m0s
[21:31:01.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:41:0)
[21:31:01.601] Timer tick, asking Buildbucket for the build status
[21:31:01.975] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:01.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:42:0) after 1m0s
[21:32:01.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:42:0)
[21:32:01.950] Timer tick, asking Buildbucket for the build status
[21:32:02.052] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:02.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:43:0) after 1m0s
[21:33:02.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:43:0)
[21:33:02.081] Timer tick, asking Buildbucket for the build status
[21:33:02.490] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:02.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:44:0) after 1m0s
[21:34:02.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:44:0)
[21:34:02.524] Timer tick, asking Buildbucket for the build status
[21:34:02.661] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:02.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:45:0) after 1m0s
[21:35:02.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:45:0)
[21:35:02.671] Timer tick, asking Buildbucket for the build status
[21:35:02.712] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:02.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:46:0) after 1m0s
[21:36:02.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:46:0)
[21:36:02.758] Timer tick, asking Buildbucket for the build status
[21:36:02.992] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:02.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:47:0) after 1m0s
[21:37:03.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:47:0)
[21:37:03.045] Timer tick, asking Buildbucket for the build status
[21:37:03.106] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:03.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:48:0) after 1m0s
[21:38:03.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:48:0)
[21:38:03.400] Timer tick, asking Buildbucket for the build status
[21:38:03.558] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:03.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:49:0) after 1m0s
[21:39:03.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:49:0)
[21:39:03.584] Timer tick, asking Buildbucket for the build status
[21:39:03.656] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:03.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:50:0) after 1m0s
[21:40:04.658] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:50:0)
[21:40:04.658] Timer tick, asking Buildbucket for the build status
[21:40:05.025] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:05.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:51:0) after 1m0s
[21:41:05.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:51:0)
[21:41:05.226] Timer tick, asking Buildbucket for the build status
[21:41:05.581] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:05.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:52:0) after 1m0s
[21:42:05.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:52:0)
[21:42:05.608] Timer tick, asking Buildbucket for the build status
[21:42:05.747] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:05.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:53:0) after 1m0s
[21:43:05.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:53:0)
[21:43:05.738] Timer tick, asking Buildbucket for the build status
[21:43:06.042] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:06.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:54:0) after 1m0s
[21:44:06.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:54:0)
[21:44:06.147] Timer tick, asking Buildbucket for the build status
[21:44:06.216] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:06.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:55:0) after 1m0s
[21:45:06.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:55:0)
[21:45:06.277] Timer tick, asking Buildbucket for the build status
[21:45:06.576] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:06.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:56:0) after 1m0s
[21:46:06.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:56:0)
[21:46:06.601] Timer tick, asking Buildbucket for the build status
[21:46:07.734] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:07.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:57:0) after 1m0s
[21:47:07.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:57:0)
[21:47:07.762] Timer tick, asking Buildbucket for the build status
[21:47:08.114] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:08.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:58:0) after 1m0s
[21:48:08.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:58:0)
[21:48:08.171] Timer tick, asking Buildbucket for the build status
[21:48:08.272] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:08.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:59:0) after 1m0s
[21:49:08.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:59:0)
[21:49:08.369] Timer tick, asking Buildbucket for the build status
[21:49:08.440] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:08.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:60:0) after 1m0s
[21:50:08.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:60:0)
[21:50:08.493] Timer tick, asking Buildbucket for the build status
[21:50:08.543] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:08.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:61:0) after 1m0s
[21:51:08.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:61:0)
[21:51:08.519] Timer tick, asking Buildbucket for the build status
[21:51:08.669] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:08.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:62:0) after 1m0s
[21:52:08.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:62:0)
[21:52:08.705] Timer tick, asking Buildbucket for the build status
[21:52:08.813] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:08.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:63:0) after 1m0s
[21:53:08.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:63:0)
[21:53:08.830] Timer tick, asking Buildbucket for the build status
[21:53:08.905] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:64:0) after 1m0s
[21:54:08.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:64:0)
[21:54:08.936] Timer tick, asking Buildbucket for the build status
[21:54:09.261] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:09.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:65:0) after 1m0s
[21:55:09.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:65:0)
[21:55:09.257] Timer tick, asking Buildbucket for the build status
[21:55:09.298] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:09.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:66:0) after 1m0s
[21:56:09.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:66:0)
[21:56:09.339] Timer tick, asking Buildbucket for the build status
[21:56:09.432] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:09.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:67:0) after 1m0s
[21:57:09.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:67:0)
[21:57:09.501] Timer tick, asking Buildbucket for the build status
[21:57:09.877] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:09.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:68:0) after 1m0s
[21:58:09.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:68:0)
[21:58:09.919] Timer tick, asking Buildbucket for the build status
[21:58:09.962] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:09.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:69:0) after 1m0s
[21:59:10.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:69:0)
[21:59:10.004] Timer tick, asking Buildbucket for the build status
[21:59:10.092] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:10.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:70:0) after 1m0s
[22:00:10.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:70:0)
[22:00:10.194] Timer tick, asking Buildbucket for the build status
[22:00:10.459] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:10.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:71:0) after 1m0s
[22:01:10.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:71:0)
[22:01:10.571] Timer tick, asking Buildbucket for the build status
[22:01:10.699] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:10.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:72:0) after 1m0s
[22:02:10.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:72:0)
[22:02:10.772] Timer tick, asking Buildbucket for the build status
[22:02:10.821] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:10.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:73:0) after 1m0s
[22:03:10.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:73:0)
[22:03:10.845] Timer tick, asking Buildbucket for the build status
[22:03:11.686] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:11.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:74:0) after 1m0s
[22:04:11.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:74:0)
[22:04:11.703] Timer tick, asking Buildbucket for the build status
[22:04:11.752] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:11.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:75:0) after 1m0s
[22:05:11.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:75:0)
[22:05:11.743] Timer tick, asking Buildbucket for the build status
[22:05:11.989] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:11.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:76:0) after 1m0s
[22:06:12.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:76:0)
[22:06:12.002] Timer tick, asking Buildbucket for the build status
[22:06:12.694] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:12.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:77:0) after 1m0s
[22:07:12.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:77:0)
[22:07:12.693] Timer tick, asking Buildbucket for the build status
[22:07:13.532] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:13.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:78:0) after 1m0s
[22:08:13.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:78:0)
[22:08:13.560] Timer tick, asking Buildbucket for the build status
[22:08:13.624] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:13.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:79:0) after 1m0s
[22:09:13.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:79:0)
[22:09:13.664] Timer tick, asking Buildbucket for the build status
[22:09:13.843] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:13.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:80:0) after 1m0s
[22:10:13.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:80:0)
[22:10:13.877] Timer tick, asking Buildbucket for the build status
[22:10:13.997] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:13.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:81:0) after 1m0s
[22:11:14.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:81:0)
[22:11:14.098] Timer tick, asking Buildbucket for the build status
[22:11:14.165] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:14.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:82:0) after 1m0s
[22:12:14.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:82:0)
[22:12:14.206] Timer tick, asking Buildbucket for the build status
[22:12:14.383] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:14.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:83:0) after 1m0s
[22:13:14.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:83:0)
[22:13:14.827] Timer tick, asking Buildbucket for the build status
[22:13:15.170] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:15.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:84:0) after 1m0s
[22:14:15.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:84:0)
[22:14:15.231] Timer tick, asking Buildbucket for the build status
[22:14:15.340] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:15.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:85:0) after 1m0s
[22:15:15.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:85:0)
[22:15:15.343] Timer tick, asking Buildbucket for the build status
[22:15:15.427] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:15.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:86:0) after 1m0s
[22:16:15.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:86:0)
[22:16:15.416] Timer tick, asking Buildbucket for the build status
[22:16:15.697] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:15.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:87:0) after 1m0s
[22:17:15.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:87:0)
[22:17:15.716] Timer tick, asking Buildbucket for the build status
[22:17:16.446] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:16.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:88:0) after 1m0s
[22:18:16.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:88:0)
[22:18:16.469] Timer tick, asking Buildbucket for the build status
[22:18:16.665] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:16.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:89:0) after 1m0s
[22:19:16.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:89:0)
[22:19:16.691] Timer tick, asking Buildbucket for the build status
[22:19:16.738] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:16.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:90:0) after 1m0s
[22:20:16.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:90:0)
[22:20:16.828] Timer tick, asking Buildbucket for the build status
[22:20:16.893] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:16.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:91:0) after 1m0s
[22:21:16.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:91:0)
[22:21:16.951] Timer tick, asking Buildbucket for the build status
[22:21:17.138] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:17.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:92:0) after 1m0s
[22:22:17.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:92:0)
[22:22:17.160] Timer tick, asking Buildbucket for the build status
[22:22:17.215] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:17.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:93:0) after 1m0s
[22:23:17.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:93:0)
[22:23:17.610] Timer tick, asking Buildbucket for the build status
[22:23:18.063] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:18.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:94:0) after 1m0s
[22:24:18.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:94:0)
[22:24:18.098] Timer tick, asking Buildbucket for the build status
[22:24:18.176] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:18.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:95:0) after 1m0s
[22:25:18.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:95:0)
[22:25:18.213] Timer tick, asking Buildbucket for the build status
[22:25:18.302] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:18.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:96:0) after 1m0s
[22:26:18.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:96:0)
[22:26:18.314] Timer tick, asking Buildbucket for the build status
[22:26:18.465] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:18.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:97:0) after 1m0s
[22:27:18.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:97:0)
[22:27:18.489] Timer tick, asking Buildbucket for the build status
[22:27:18.537] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:18.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:98:0) after 1m0s
[22:28:18.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:98:0)
[22:28:18.547] Timer tick, asking Buildbucket for the build status
[22:28:18.604] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:18.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:99:0) after 1m0s
[22:29:18.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:99:0)
[22:29:18.686] Timer tick, asking Buildbucket for the build status
[22:29:18.871] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:18.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:100:0) after 1m0s
[22:30:18.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:100:0)
[22:30:18.908] Timer tick, asking Buildbucket for the build status
[22:30:19.007] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:19.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:101:0) after 1m0s
[22:31:19.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:101:0)
[22:31:19.031] Timer tick, asking Buildbucket for the build status
[22:31:19.275] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:19.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:102:0) after 1m0s
[22:32:19.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:102:0)
[22:32:19.306] Timer tick, asking Buildbucket for the build status
[22:32:19.346] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:19.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:103:0) after 1m0s
[22:33:19.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:103:0)
[22:33:19.381] Timer tick, asking Buildbucket for the build status
[22:33:19.461] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:19.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:104:0) after 1m0s
[22:34:19.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:104:0)
[22:34:19.484] Timer tick, asking Buildbucket for the build status
[22:34:19.750] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:19.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:105:0) after 1m0s
[22:35:19.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:105:0)
[22:35:19.805] Timer tick, asking Buildbucket for the build status
[22:35:20.212] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:20.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:106:0) after 1m0s
[22:36:20.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:106:0)
[22:36:20.246] Timer tick, asking Buildbucket for the build status
[22:36:20.503] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:20.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:107:0) after 1m0s
[22:37:20.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:107:0)
[22:37:20.533] Timer tick, asking Buildbucket for the build status
[22:37:21.332] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:21.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:108:0) after 1m0s
[22:38:21.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:108:0)
[22:38:21.372] Timer tick, asking Buildbucket for the build status
[22:38:21.413] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:21.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:109:0) after 1m0s
[22:39:21.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:109:0)
[22:39:21.405] Timer tick, asking Buildbucket for the build status
[22:39:21.446] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:21.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:110:0) after 1m0s
[22:40:21.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:110:0)
[22:40:21.520] Timer tick, asking Buildbucket for the build status
[22:40:21.602] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:21.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:111:0) after 1m0s
[22:41:21.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:111:0)
[22:41:21.639] Timer tick, asking Buildbucket for the build status
[22:41:21.711] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:21.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:112:0) after 1m0s
[22:42:21.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:112:0)
[22:42:21.784] Timer tick, asking Buildbucket for the build status
[22:42:22.139] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:22.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:113:0) after 1m0s
[22:43:34.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:113:0)
[22:43:34.384] Timer tick, asking Buildbucket for the build status
[22:43:34.449] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:34.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:114:0) after 1m0s
[22:44:34.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:114:0)
[22:44:34.534] Timer tick, asking Buildbucket for the build status
[22:44:35.427] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:35.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:115:0) after 1m0s
[22:45:35.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:115:0)
[22:45:35.435] Timer tick, asking Buildbucket for the build status
[22:45:35.512] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:35.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:116:0) after 1m0s
[22:46:35.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:116:0)
[22:46:35.507] Timer tick, asking Buildbucket for the build status
[22:46:35.721] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:35.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:117:0) after 1m0s
[22:47:35.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:117:0)
[22:47:35.792] Timer tick, asking Buildbucket for the build status
[22:47:36.645] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:36.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:118:0) after 1m0s
[22:48:36.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:118:0)
[22:48:36.678] Timer tick, asking Buildbucket for the build status
[22:48:36.788] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:36.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:119:0) after 1m0s
[22:49:36.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:119:0)
[22:49:36.852] Timer tick, asking Buildbucket for the build status
[22:49:37.022] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:37.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:120:0) after 1m0s
[22:50:37.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:120:0)
[22:50:37.190] Timer tick, asking Buildbucket for the build status
[22:50:37.283] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:37.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:121:0) after 1m0s
[22:51:37.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:121:0)
[22:51:37.307] Timer tick, asking Buildbucket for the build status
[22:51:37.361] Build 8913179862511044240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:37.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078609924989499872:122:0) after 1m0s
[22:52:23.221] Received PubSub notification, asking Buildbucket for the build status
[22:52:23.271] Build 8913179862511044240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:52:23.271] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558133541847279",
"created_by": "project:fuchsia",
"created_ts": "1558126309821507",
"failure_reason": "BUILD_FAILURE",
"id": "8913179862511044240",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"182c638fe090803a9e8ad2b357bb1df9500ae7af\"}}",
"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-prod-1-us-central1-c-2-4bb4\", \"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\": \"182c638fe090803a9e8ad2b357bb1df9500ae7af\", \"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-prod-1-us-central1-c-2-4bb4\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4301-0053cb9\"], \"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": "1558126318658373",
"status": "COMPLETED",
"status_changed_ts": "1558126320483166",
"tags": [
"builder:clang",
"buildset:commit/git/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/182c638fe090803a9e8ad2b357bb1df9500ae7af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078609924989499872",
"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/8913179862511044240/+/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:44e062429e1b7510",
"user_agent:luci-scheduler"
],
"updated_ts": "1558133541956248",
"url": "https://ci.chromium.org/b/8913179862511044240",
"utcnow_ts": "1558133543263200"
}
}
[22:52:23.271] Invocation finished in 2h0m35.196983807s with status FAILED