[17:41:46.474] New invocation is queued and will start shortly
[17:41:48.172] Starting the invocation (attempt 1)
[17:41:48.210] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:41:48.210] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:41:48.210] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078078298663612416",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"eafe8ef6f2b44baf5a84658caca90c2f9c1849ca\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078078298663612416",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"gitiles_ref:refs/heads/master"
]
}
[17:41:48.862] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558633308314898",
"id": "8912648236059963392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eafe8ef6f2b44baf5a84658caca90c2f9c1849ca\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558633308689551",
"tags": [
"builder:clang",
"buildset:commit/git/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078078298663612416",
"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/8912648236059963392/+/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": "1558633308689645",
"url": "https://ci.chromium.org/b/8912648236059963392",
"utcnow_ts": "1558633308853557"
}
}
[17:41:48.862] Task URL: https://ci.chromium.org/b/8912648236059963392
[17:41:48.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:2:0) after 1m0s
[17:42:06.724] Received PubSub notification, asking Buildbucket for the build status
[17:42:06.848] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:48.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:2:0)
[17:42:48.902] Timer tick, asking Buildbucket for the build status
[17:42:49.107] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:49.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:4:0) after 1m0s
[17:43:49.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:4:0)
[17:43:49.130] Timer tick, asking Buildbucket for the build status
[17:43:49.757] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:49.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:5:0) after 1m0s
[17:44:49.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:5:0)
[17:44:49.819] Timer tick, asking Buildbucket for the build status
[17:44:50.635] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:50.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:6:0) after 1m0s
[17:45:50.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:6:0)
[17:45:50.884] Timer tick, asking Buildbucket for the build status
[17:45:51.835] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:51.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:7:0) after 1m0s
[17:46:51.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:7:0)
[17:46:51.898] Timer tick, asking Buildbucket for the build status
[17:46:51.932] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:51.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:8:0) after 1m0s
[17:47:52.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:8:0)
[17:47:52.021] Timer tick, asking Buildbucket for the build status
[17:47:52.120] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:52.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:9:0) after 1m0s
[17:48:52.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:9:0)
[17:48:52.181] Timer tick, asking Buildbucket for the build status
[17:48:52.426] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:52.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:10:0) after 1m0s
[17:49:52.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:10:0)
[17:49:52.457] Timer tick, asking Buildbucket for the build status
[17:49:52.556] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:52.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:11:0) after 1m0s
[17:50:52.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:11:0)
[17:50:52.608] Timer tick, asking Buildbucket for the build status
[17:50:52.728] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:52.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:12:0) after 1m0s
[17:51:53.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:12:0)
[17:51:53.054] Timer tick, asking Buildbucket for the build status
[17:51:53.142] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:53.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:13:0) after 1m0s
[17:52:53.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:13:0)
[17:52:53.458] Timer tick, asking Buildbucket for the build status
[17:52:53.586] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:53.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:14:0) after 1m0s
[17:53:53.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:14:0)
[17:53:53.790] Timer tick, asking Buildbucket for the build status
[17:53:53.829] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:53.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:15:0) after 1m0s
[17:54:54.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:15:0)
[17:54:54.268] Timer tick, asking Buildbucket for the build status
[17:54:54.811] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:54.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:16:0) after 1m0s
[17:55:54.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:16:0)
[17:55:54.946] Timer tick, asking Buildbucket for the build status
[17:55:55.315] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:55.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:17:0) after 1m0s
[17:56:55.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:17:0)
[17:56:55.307] Timer tick, asking Buildbucket for the build status
[17:56:55.724] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:55.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:18:0) after 1m0s
[17:57:55.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:18:0)
[17:57:55.747] Timer tick, asking Buildbucket for the build status
[17:57:56.217] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:56.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:19:0) after 1m0s
[17:58:56.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:19:0)
[17:58:56.327] Timer tick, asking Buildbucket for the build status
[17:58:56.391] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:56.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:20:0) after 1m0s
[17:59:56.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:20:0)
[17:59:56.472] Timer tick, asking Buildbucket for the build status
[17:59:57.004] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:57.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:21:0) after 1m0s
[18:00:57.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:21:0)
[18:00:57.138] Timer tick, asking Buildbucket for the build status
[18:00:57.338] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:57.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:22:0) after 1m0s
[18:01:57.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:22:0)
[18:01:57.365] Timer tick, asking Buildbucket for the build status
[18:01:57.502] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:57.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:23:0) after 1m0s
[18:02:57.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:23:0)
[18:02:57.731] Timer tick, asking Buildbucket for the build status
[18:02:57.890] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:57.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:24:0) after 1m0s
[18:03:58.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:24:0)
[18:03:58.234] Timer tick, asking Buildbucket for the build status
[18:03:58.306] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:58.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:25:0) after 1m0s
[18:04:58.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:25:0)
[18:04:58.469] Timer tick, asking Buildbucket for the build status
[18:04:58.715] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:58.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:26:0) after 1m0s
[18:05:58.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:26:0)
[18:05:58.851] Timer tick, asking Buildbucket for the build status
[18:05:59.119] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:59.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:27:0) after 1m0s
[18:06:59.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:27:0)
[18:06:59.321] Timer tick, asking Buildbucket for the build status
[18:06:59.647] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:59.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:28:0) after 1m0s
[18:07:59.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:28:0)
[18:07:59.760] Timer tick, asking Buildbucket for the build status
[18:08:00.256] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:00.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:29:0) after 1m0s
[18:09:00.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:29:0)
[18:09:00.321] Timer tick, asking Buildbucket for the build status
[18:09:00.479] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:00.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:30:0) after 1m0s
[18:10:00.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:30:0)
[18:10:00.471] Timer tick, asking Buildbucket for the build status
[18:10:00.562] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:00.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:31:0) after 1m0s
[18:11:00.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:31:0)
[18:11:00.632] Timer tick, asking Buildbucket for the build status
[18:11:00.795] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:00.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:32:0) after 1m0s
[18:12:00.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:32:0)
[18:12:00.904] Timer tick, asking Buildbucket for the build status
[18:12:01.683] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:01.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:33:0) after 1m0s
[18:13:01.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:33:0)
[18:13:01.779] Timer tick, asking Buildbucket for the build status
[18:13:01.819] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:01.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:34:0) after 1m0s
[18:14:02.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:34:0)
[18:14:02.014] Timer tick, asking Buildbucket for the build status
[18:14:02.190] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:02.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:35:0) after 1m0s
[18:15:02.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:35:0)
[18:15:02.243] Timer tick, asking Buildbucket for the build status
[18:15:02.334] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:02.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:36:0) after 1m0s
[18:16:02.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:36:0)
[18:16:02.406] Timer tick, asking Buildbucket for the build status
[18:16:02.481] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:02.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:37:0) after 1m0s
[18:17:02.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:37:0)
[18:17:02.535] Timer tick, asking Buildbucket for the build status
[18:17:02.671] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:02.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:38:0) after 1m0s
[18:18:02.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:38:0)
[18:18:02.710] Timer tick, asking Buildbucket for the build status
[18:18:03.016] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:03.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:39:0) after 1m0s
[18:19:03.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:39:0)
[18:19:03.108] Timer tick, asking Buildbucket for the build status
[18:19:05.882] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:05.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:40:0) after 1m0s
[18:20:06.051] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:40:0)
[18:20:06.051] Timer tick, asking Buildbucket for the build status
[18:20:06.189] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:06.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:41:0) after 1m0s
[18:21:06.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:41:0)
[18:21:06.310] Timer tick, asking Buildbucket for the build status
[18:21:06.382] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:06.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:42:0) after 1m0s
[18:22:06.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:42:0)
[18:22:06.410] Timer tick, asking Buildbucket for the build status
[18:22:06.680] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:06.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:43:0) after 1m0s
[18:23:06.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:43:0)
[18:23:06.734] Timer tick, asking Buildbucket for the build status
[18:23:06.783] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:06.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:44:0) after 1m0s
[18:24:06.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:44:0)
[18:24:06.814] Timer tick, asking Buildbucket for the build status
[18:24:06.887] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:06.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:45:0) after 1m0s
[18:25:06.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:45:0)
[18:25:06.932] Timer tick, asking Buildbucket for the build status
[18:25:07.036] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:07.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:46:0) after 1m0s
[18:26:07.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:46:0)
[18:26:07.084] Timer tick, asking Buildbucket for the build status
[18:26:07.180] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:07.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:47:0) after 1m0s
[18:27:07.250] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:47:0)
[18:27:07.250] Timer tick, asking Buildbucket for the build status
[18:27:07.317] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:07.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:48:0) after 1m0s
[18:28:07.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:48:0)
[18:28:07.416] Timer tick, asking Buildbucket for the build status
[18:28:07.475] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:07.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:49:0) after 1m0s
[18:29:07.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:49:0)
[18:29:07.522] Timer tick, asking Buildbucket for the build status
[18:29:07.564] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:07.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:50:0) after 1m0s
[18:30:07.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:50:0)
[18:30:07.720] Timer tick, asking Buildbucket for the build status
[18:30:07.881] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:07.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:51:0) after 1m0s
[18:31:08.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:51:0)
[18:31:08.102] Timer tick, asking Buildbucket for the build status
[18:31:08.310] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:08.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:52:0) after 1m0s
[18:32:08.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:52:0)
[18:32:08.402] Timer tick, asking Buildbucket for the build status
[18:32:08.501] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:08.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:53:0) after 1m0s
[18:33:08.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:53:0)
[18:33:08.548] Timer tick, asking Buildbucket for the build status
[18:33:08.810] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:08.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:54:0) after 1m0s
[18:34:08.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:54:0)
[18:34:08.888] Timer tick, asking Buildbucket for the build status
[18:34:08.930] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:08.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:55:0) after 1m0s
[18:35:08.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:55:0)
[18:35:08.947] Timer tick, asking Buildbucket for the build status
[18:35:09.092] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:09.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:56:0) after 1m0s
[18:36:09.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:56:0)
[18:36:09.114] Timer tick, asking Buildbucket for the build status
[18:36:09.794] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:09.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:57:0) after 1m0s
[18:37:09.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:57:0)
[18:37:09.832] Timer tick, asking Buildbucket for the build status
[18:37:09.969] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:09.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:58:0) after 1m0s
[18:38:09.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:58:0)
[18:38:09.997] Timer tick, asking Buildbucket for the build status
[18:38:10.039] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:10.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:59:0) after 1m0s
[18:39:10.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:59:0)
[18:39:10.217] Timer tick, asking Buildbucket for the build status
[18:39:10.442] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:10.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:60:0) after 1m0s
[18:40:10.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:60:0)
[18:40:10.427] Timer tick, asking Buildbucket for the build status
[18:40:10.561] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:10.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:61:0) after 1m0s
[18:41:10.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:61:0)
[18:41:10.815] Timer tick, asking Buildbucket for the build status
[18:41:11.947] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:11.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:62:0) after 1m0s
[18:42:11.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:62:0)
[18:42:11.973] Timer tick, asking Buildbucket for the build status
[18:42:12.118] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:12.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:63:0) after 1m0s
[18:43:12.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:63:0)
[18:43:12.175] Timer tick, asking Buildbucket for the build status
[18:43:12.238] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:12.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:64:0) after 1m0s
[18:44:12.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:64:0)
[18:44:12.313] Timer tick, asking Buildbucket for the build status
[18:44:12.423] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:12.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:65:0) after 1m0s
[18:45:12.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:65:0)
[18:45:12.448] Timer tick, asking Buildbucket for the build status
[18:45:12.567] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:12.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:66:0) after 1m0s
[18:46:12.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:66:0)
[18:46:12.660] Timer tick, asking Buildbucket for the build status
[18:46:12.971] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:12.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:67:0) after 1m0s
[18:47:13.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:67:0)
[18:47:13.057] Timer tick, asking Buildbucket for the build status
[18:47:13.129] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:13.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:68:0) after 1m0s
[18:48:13.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:68:0)
[18:48:13.157] Timer tick, asking Buildbucket for the build status
[18:48:13.304] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:13.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:69:0) after 1m0s
[18:49:13.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:69:0)
[18:49:13.343] Timer tick, asking Buildbucket for the build status
[18:49:13.571] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:13.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:70:0) after 1m0s
[18:50:13.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:70:0)
[18:50:13.599] Timer tick, asking Buildbucket for the build status
[18:50:13.656] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:13.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:71:0) after 1m0s
[18:51:13.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:71:0)
[18:51:13.699] Timer tick, asking Buildbucket for the build status
[18:51:13.803] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:13.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:72:0) after 1m0s
[18:52:13.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:72:0)
[18:52:13.837] Timer tick, asking Buildbucket for the build status
[18:52:14.289] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:14.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:73:0) after 1m0s
[18:53:14.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:73:0)
[18:53:14.399] Timer tick, asking Buildbucket for the build status
[18:53:14.964] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:14.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:74:0) after 1m0s
[18:54:14.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:74:0)
[18:54:14.994] Timer tick, asking Buildbucket for the build status
[18:54:15.078] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:15.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:75:0) after 1m0s
[18:55:15.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:75:0)
[18:55:15.098] Timer tick, asking Buildbucket for the build status
[18:55:15.172] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:15.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:76:0) after 1m0s
[18:56:15.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:76:0)
[18:56:15.265] Timer tick, asking Buildbucket for the build status
[18:56:15.354] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:15.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:77:0) after 1m0s
[18:57:15.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:77:0)
[18:57:15.383] Timer tick, asking Buildbucket for the build status
[18:57:15.593] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:15.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:78:0) after 1m0s
[18:58:15.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:78:0)
[18:58:15.970] Timer tick, asking Buildbucket for the build status
[18:58:16.174] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:16.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:79:0) after 1m0s
[18:59:16.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:79:0)
[18:59:16.269] Timer tick, asking Buildbucket for the build status
[18:59:16.525] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:16.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:80:0) after 1m0s
[19:00:16.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:80:0)
[19:00:16.682] Timer tick, asking Buildbucket for the build status
[19:00:16.796] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:16.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:81:0) after 1m0s
[19:01:16.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:81:0)
[19:01:16.836] Timer tick, asking Buildbucket for the build status
[19:01:16.890] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:16.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:82:0) after 1m0s
[19:02:16.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:82:0)
[19:02:16.970] Timer tick, asking Buildbucket for the build status
[19:02:17.240] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:17.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:83:0) after 1m0s
[19:03:17.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:83:0)
[19:03:17.374] Timer tick, asking Buildbucket for the build status
[19:03:17.460] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:17.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:84:0) after 1m0s
[19:04:17.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:84:0)
[19:04:17.524] Timer tick, asking Buildbucket for the build status
[19:04:17.566] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:17.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:85:0) after 1m0s
[19:05:17.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:85:0)
[19:05:17.600] Timer tick, asking Buildbucket for the build status
[19:05:17.727] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:17.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:86:0) after 1m0s
[19:06:17.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:86:0)
[19:06:17.753] Timer tick, asking Buildbucket for the build status
[19:06:18.093] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:18.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:87:0) after 1m0s
[19:07:18.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:87:0)
[19:07:18.127] Timer tick, asking Buildbucket for the build status
[19:07:18.650] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:18.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:88:0) after 1m0s
[19:08:18.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:88:0)
[19:08:18.842] Timer tick, asking Buildbucket for the build status
[19:08:18.952] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:18.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:89:0) after 1m0s
[19:09:18.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:89:0)
[19:09:18.945] Timer tick, asking Buildbucket for the build status
[19:09:19.114] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:19.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:90:0) after 1m0s
[19:10:19.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:90:0)
[19:10:19.217] Timer tick, asking Buildbucket for the build status
[19:10:19.276] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:19.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:91:0) after 1m0s
[19:11:19.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:91:0)
[19:11:19.301] Timer tick, asking Buildbucket for the build status
[19:11:19.390] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:19.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:92:0) after 1m0s
[19:12:19.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:92:0)
[19:12:19.438] Timer tick, asking Buildbucket for the build status
[19:12:19.485] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:19.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:93:0) after 1m0s
[19:13:19.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:93:0)
[19:13:19.539] Timer tick, asking Buildbucket for the build status
[19:13:19.599] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:19.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:94:0) after 1m0s
[19:14:19.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:94:0)
[19:14:19.628] Timer tick, asking Buildbucket for the build status
[19:14:19.834] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:19.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:95:0) after 1m0s
[19:15:19.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:95:0)
[19:15:19.855] Timer tick, asking Buildbucket for the build status
[19:15:19.924] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:19.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:96:0) after 1m0s
[19:16:19.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:96:0)
[19:16:19.941] Timer tick, asking Buildbucket for the build status
[19:16:19.990] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:19.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:97:0) after 1m0s
[19:17:20.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:97:0)
[19:17:20.016] Timer tick, asking Buildbucket for the build status
[19:17:20.098] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:20.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:98:0) after 1m0s
[19:18:20.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:98:0)
[19:18:20.175] Timer tick, asking Buildbucket for the build status
[19:18:20.231] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:20.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:99:0) after 1m0s
[19:19:20.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:99:0)
[19:19:20.312] Timer tick, asking Buildbucket for the build status
[19:19:20.375] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:20.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:100:0) after 1m0s
[19:20:20.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:100:0)
[19:20:20.519] Timer tick, asking Buildbucket for the build status
[19:20:20.794] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:20.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:101:0) after 1m0s
[19:21:20.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:101:0)
[19:21:20.821] Timer tick, asking Buildbucket for the build status
[19:21:20.865] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:102:0) after 1m0s
[19:22:21.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:102:0)
[19:22:21.004] Timer tick, asking Buildbucket for the build status
[19:22:21.089] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:21.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:103:0) after 1m0s
[19:23:21.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:103:0)
[19:23:21.084] Timer tick, asking Buildbucket for the build status
[19:23:21.193] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:21.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:104:0) after 1m0s
[19:24:21.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:104:0)
[19:24:21.236] Timer tick, asking Buildbucket for the build status
[19:24:21.304] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:21.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:105:0) after 1m0s
[19:25:21.322] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:105:0)
[19:25:21.322] Timer tick, asking Buildbucket for the build status
[19:25:21.444] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:21.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:106:0) after 1m0s
[19:26:21.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:106:0)
[19:26:21.523] Timer tick, asking Buildbucket for the build status
[19:26:21.586] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:21.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:107:0) after 1m0s
[19:27:21.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:107:0)
[19:27:21.678] Timer tick, asking Buildbucket for the build status
[19:27:21.885] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:21.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:108:0) after 1m0s
[19:28:21.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:108:0)
[19:28:21.913] Timer tick, asking Buildbucket for the build status
[19:28:21.981] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:21.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:109:0) after 1m0s
[19:29:22.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:109:0)
[19:29:22.014] Timer tick, asking Buildbucket for the build status
[19:29:22.261] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:22.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:110:0) after 1m0s
[19:30:22.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:110:0)
[19:30:22.306] Timer tick, asking Buildbucket for the build status
[19:30:22.771] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:22.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:111:0) after 1m0s
[19:31:22.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:111:0)
[19:31:22.818] Timer tick, asking Buildbucket for the build status
[19:31:23.270] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:23.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:112:0) after 1m0s
[19:32:23.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:112:0)
[19:32:23.301] Timer tick, asking Buildbucket for the build status
[19:32:23.489] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:23.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:113:0) after 1m0s
[19:33:23.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:113:0)
[19:33:23.597] Timer tick, asking Buildbucket for the build status
[19:33:24.224] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:24.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:114:0) after 1m0s
[19:34:24.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:114:0)
[19:34:24.273] Timer tick, asking Buildbucket for the build status
[19:34:24.750] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:24.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:115:0) after 1m0s
[19:35:24.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:115:0)
[19:35:24.823] Timer tick, asking Buildbucket for the build status
[19:35:24.950] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:24.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:116:0) after 1m0s
[19:36:25.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:116:0)
[19:36:25.044] Timer tick, asking Buildbucket for the build status
[19:36:25.177] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:25.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:117:0) after 1m0s
[19:37:25.322] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:117:0)
[19:37:25.322] Timer tick, asking Buildbucket for the build status
[19:37:25.680] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:25.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:118:0) after 1m0s
[19:38:25.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:118:0)
[19:38:25.717] Timer tick, asking Buildbucket for the build status
[19:38:25.912] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:25.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:119:0) after 1m0s
[19:39:25.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:119:0)
[19:39:25.940] Timer tick, asking Buildbucket for the build status
[19:39:26.224] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:26.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:120:0) after 1m0s
[19:40:26.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:120:0)
[19:40:26.248] Timer tick, asking Buildbucket for the build status
[19:40:26.399] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:26.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:121:0) after 1m0s
[19:41:26.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:121:0)
[19:41:26.436] Timer tick, asking Buildbucket for the build status
[19:41:26.490] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:122:0) after 1m0s
[19:42:26.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:122:0)
[19:42:26.695] Timer tick, asking Buildbucket for the build status
[19:42:26.733] Build 8912648236059963392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:26.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078078298663612416:123:0) after 1m0s
[19:42:31.674] Received PubSub notification, asking Buildbucket for the build status
[19:42:31.779] Build 8912648236059963392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:42:31.779] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558640551033979",
"created_by": "project:fuchsia",
"created_ts": "1558633308314898",
"failure_reason": "BUILD_FAILURE",
"id": "8912648236059963392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eafe8ef6f2b44baf5a84658caca90c2f9c1849ca\"}}",
"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-b-1-r1ka\", \"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\": \"eafe8ef6f2b44baf5a84658caca90c2f9c1849ca\", \"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-b-1-r1ka\"], \"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\": [\"4303-bae210e\"], \"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": "1558633325885519",
"status": "COMPLETED",
"status_changed_ts": "1558633326149641",
"tags": [
"builder:clang",
"buildset:commit/git/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eafe8ef6f2b44baf5a84658caca90c2f9c1849ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078078298663612416",
"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/8912648236059963392/+/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:44fe9a6d1094ae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558640551274768",
"url": "https://ci.chromium.org/b/8912648236059963392",
"utcnow_ts": "1558640551765316"
}
}
[19:42:31.779] Invocation finished in 2h0m45.331592667s with status FAILED