[18:49:58.946] New invocation is queued and will start shortly
[18:50:00.072] Starting the invocation (attempt 1)
[18:50:00.145] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:50:00.145] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:50:00.145] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080701319412589136",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"6e619075466d46de32b2c1ad0c71c67c81c1281f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080701319412589136",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"gitiles_ref:refs/heads/master"
]
}
[18:50:01.407] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556131800355382",
"id": "8915271257269632608",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6e619075466d46de32b2c1ad0c71c67c81c1281f\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556131801217967",
"tags": [
"builder:clang",
"buildset:commit/git/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080701319412589136",
"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/8915271257269632608/+/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": "1556131801218087",
"url": "https://ci.chromium.org/b/8915271257269632608",
"utcnow_ts": "1556131801391995"
}
}
[18:50:01.407] Task URL: https://ci.chromium.org/b/8915271257269632608
[18:50:01.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:2:0) after 1m0s
[18:50:40.375] Received PubSub notification, asking Buildbucket for the build status
[18:50:40.443] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:01.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:2:0)
[18:51:01.416] Timer tick, asking Buildbucket for the build status
[18:51:01.536] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:01.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:4:0) after 1m0s
[18:52:01.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:4:0)
[18:52:01.569] Timer tick, asking Buildbucket for the build status
[18:52:01.667] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:01.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:5:0) after 1m0s
[18:53:01.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:5:0)
[18:53:01.646] Timer tick, asking Buildbucket for the build status
[18:53:01.765] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:01.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:6:0) after 1m0s
[18:54:01.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:6:0)
[18:54:01.794] Timer tick, asking Buildbucket for the build status
[18:54:01.867] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:01.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:7:0) after 1m0s
[18:55:01.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:7:0)
[18:55:01.887] Timer tick, asking Buildbucket for the build status
[18:55:01.953] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:01.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:8:0) after 1m0s
[18:56:01.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:8:0)
[18:56:01.951] Timer tick, asking Buildbucket for the build status
[18:56:01.993] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:01.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:9:0) after 1m0s
[18:57:02.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:9:0)
[18:57:02.002] Timer tick, asking Buildbucket for the build status
[18:57:02.254] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:02.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:10:0) after 1m0s
[18:58:02.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:10:0)
[18:58:02.269] Timer tick, asking Buildbucket for the build status
[18:58:02.330] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:02.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:11:0) after 1m0s
[18:59:02.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:11:0)
[18:59:02.330] Timer tick, asking Buildbucket for the build status
[18:59:02.386] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:02.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:12:0) after 1m0s
[19:00:02.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:12:0)
[19:00:02.411] Timer tick, asking Buildbucket for the build status
[19:00:02.886] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:02.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:13:0) after 1m0s
[19:01:02.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:13:0)
[19:01:02.874] Timer tick, asking Buildbucket for the build status
[19:01:02.920] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:02.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:14:0) after 1m0s
[19:02:02.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:14:0)
[19:02:02.940] Timer tick, asking Buildbucket for the build status
[19:02:03.221] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:03.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:15:0) after 1m0s
[19:03:03.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:15:0)
[19:03:03.278] Timer tick, asking Buildbucket for the build status
[19:03:03.994] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:03.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:16:0) after 1m0s
[19:04:04.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:16:0)
[19:04:04.017] Timer tick, asking Buildbucket for the build status
[19:04:04.108] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:04.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:17:0) after 1m0s
[19:05:04.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:17:0)
[19:05:04.099] Timer tick, asking Buildbucket for the build status
[19:05:04.139] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:04.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:18:0) after 1m0s
[19:06:04.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:18:0)
[19:06:04.159] Timer tick, asking Buildbucket for the build status
[19:06:04.237] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:04.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:19:0) after 1m0s
[19:07:04.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:19:0)
[19:07:04.215] Timer tick, asking Buildbucket for the build status
[19:07:04.355] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:04.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:20:0) after 1m0s
[19:08:04.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:20:0)
[19:08:04.410] Timer tick, asking Buildbucket for the build status
[19:08:04.471] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:04.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:21:0) after 1m0s
[19:09:04.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:21:0)
[19:09:04.499] Timer tick, asking Buildbucket for the build status
[19:09:08.157] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:08.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:22:0) after 1m0s
[19:10:08.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:22:0)
[19:10:08.189] Timer tick, asking Buildbucket for the build status
[19:10:08.384] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:08.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:23:0) after 1m0s
[19:11:08.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:23:0)
[19:11:08.377] Timer tick, asking Buildbucket for the build status
[19:11:08.424] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:08.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:24:0) after 1m0s
[19:12:08.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:24:0)
[19:12:08.426] Timer tick, asking Buildbucket for the build status
[19:12:08.467] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:08.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:25:0) after 1m0s
[19:13:08.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:25:0)
[19:13:08.495] Timer tick, asking Buildbucket for the build status
[19:13:08.609] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:08.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:26:0) after 1m0s
[19:14:08.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:26:0)
[19:14:08.601] Timer tick, asking Buildbucket for the build status
[19:14:08.678] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:08.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:27:0) after 1m0s
[19:15:08.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:27:0)
[19:15:08.652] Timer tick, asking Buildbucket for the build status
[19:15:08.769] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:08.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:28:0) after 1m0s
[19:16:08.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:28:0)
[19:16:08.744] Timer tick, asking Buildbucket for the build status
[19:16:08.798] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:08.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:29:0) after 1m0s
[19:17:08.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:29:0)
[19:17:08.838] Timer tick, asking Buildbucket for the build status
[19:17:08.976] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:08.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:30:0) after 1m0s
[19:18:09.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:30:0)
[19:18:09.008] Timer tick, asking Buildbucket for the build status
[19:18:09.066] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:09.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:31:0) after 1m0s
[19:19:09.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:31:0)
[19:19:09.093] Timer tick, asking Buildbucket for the build status
[19:19:09.147] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:09.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:32:0) after 1m0s
[19:20:09.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:32:0)
[19:20:09.121] Timer tick, asking Buildbucket for the build status
[19:20:09.188] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:09.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:33:0) after 1m0s
[19:21:09.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:33:0)
[19:21:09.182] Timer tick, asking Buildbucket for the build status
[19:21:09.219] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:09.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:34:0) after 1m0s
[19:22:09.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:34:0)
[19:22:09.208] Timer tick, asking Buildbucket for the build status
[19:22:09.252] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:09.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:35:0) after 1m0s
[19:23:09.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:35:0)
[19:23:09.279] Timer tick, asking Buildbucket for the build status
[19:23:09.329] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:09.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:36:0) after 1m0s
[19:24:09.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:36:0)
[19:24:09.349] Timer tick, asking Buildbucket for the build status
[19:24:09.401] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:09.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:37:0) after 1m0s
[19:25:09.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:37:0)
[19:25:09.398] Timer tick, asking Buildbucket for the build status
[19:25:09.591] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:09.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:38:0) after 1m0s
[19:26:09.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:38:0)
[19:26:09.568] Timer tick, asking Buildbucket for the build status
[19:26:09.821] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:09.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:39:0) after 1m0s
[19:27:09.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:39:0)
[19:27:09.843] Timer tick, asking Buildbucket for the build status
[19:27:09.917] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:09.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:40:0) after 1m0s
[19:28:09.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:40:0)
[19:28:09.938] Timer tick, asking Buildbucket for the build status
[19:28:10.036] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:10.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:41:0) after 1m0s
[19:29:10.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:41:0)
[19:29:10.078] Timer tick, asking Buildbucket for the build status
[19:29:10.213] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:10.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:42:0) after 1m0s
[19:30:10.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:42:0)
[19:30:10.241] Timer tick, asking Buildbucket for the build status
[19:30:10.486] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:10.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:43:0) after 1m0s
[19:31:10.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:43:0)
[19:31:10.480] Timer tick, asking Buildbucket for the build status
[19:31:10.577] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:10.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:44:0) after 1m0s
[19:32:10.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:44:0)
[19:32:10.604] Timer tick, asking Buildbucket for the build status
[19:32:10.812] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:10.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:45:0) after 1m0s
[19:33:10.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:45:0)
[19:33:10.820] Timer tick, asking Buildbucket for the build status
[19:33:10.862] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:10.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:46:0) after 1m0s
[19:34:10.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:46:0)
[19:34:10.990] Timer tick, asking Buildbucket for the build status
[19:34:11.181] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:11.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:47:0) after 1m0s
[19:35:11.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:47:0)
[19:35:11.210] Timer tick, asking Buildbucket for the build status
[19:35:11.252] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:11.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:48:0) after 1m0s
[19:36:11.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:48:0)
[19:36:11.249] Timer tick, asking Buildbucket for the build status
[19:36:11.308] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:11.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:49:0) after 1m0s
[19:37:11.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:49:0)
[19:37:11.524] Timer tick, asking Buildbucket for the build status
[19:37:11.568] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:11.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:50:0) after 1m0s
[19:38:11.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:50:0)
[19:38:11.587] Timer tick, asking Buildbucket for the build status
[19:38:11.657] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:11.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:51:0) after 1m0s
[19:39:11.681] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:51:0)
[19:39:11.681] Timer tick, asking Buildbucket for the build status
[19:39:11.728] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:11.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:52:0) after 1m0s
[19:40:11.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:52:0)
[19:40:11.724] Timer tick, asking Buildbucket for the build status
[19:40:11.785] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:11.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:53:0) after 1m0s
[19:41:11.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:53:0)
[19:41:11.795] Timer tick, asking Buildbucket for the build status
[19:41:11.843] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:11.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:54:0) after 1m0s
[19:42:11.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:54:0)
[19:42:11.865] Timer tick, asking Buildbucket for the build status
[19:42:11.906] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:11.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:55:0) after 1m0s
[19:43:11.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:55:0)
[19:43:11.948] Timer tick, asking Buildbucket for the build status
[19:43:11.993] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:11.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:56:0) after 1m0s
[19:44:12.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:56:0)
[19:44:12.019] Timer tick, asking Buildbucket for the build status
[19:44:12.085] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:12.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:57:0) after 1m0s
[19:45:12.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:57:0)
[19:45:12.118] Timer tick, asking Buildbucket for the build status
[19:45:12.217] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:12.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:58:0) after 1m0s
[19:46:12.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:58:0)
[19:46:12.262] Timer tick, asking Buildbucket for the build status
[19:46:12.324] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:12.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:59:0) after 1m0s
[19:47:12.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:59:0)
[19:47:12.391] Timer tick, asking Buildbucket for the build status
[19:47:12.441] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:12.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:60:0) after 1m0s
[19:48:12.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:60:0)
[19:48:12.465] Timer tick, asking Buildbucket for the build status
[19:48:13.546] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:13.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:61:0) after 1m0s
[19:49:13.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:61:0)
[19:49:13.545] Timer tick, asking Buildbucket for the build status
[19:49:13.640] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:13.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:62:0) after 1m0s
[19:50:13.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:62:0)
[19:50:13.651] Timer tick, asking Buildbucket for the build status
[19:50:13.775] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:13.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:63:0) after 1m0s
[19:51:13.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:63:0)
[19:51:13.798] Timer tick, asking Buildbucket for the build status
[19:51:13.835] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:13.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:64:0) after 1m0s
[19:52:13.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:64:0)
[19:52:13.887] Timer tick, asking Buildbucket for the build status
[19:52:14.115] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:14.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:65:0) after 1m0s
[19:53:14.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:65:0)
[19:53:14.107] Timer tick, asking Buildbucket for the build status
[19:53:14.167] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:14.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:66:0) after 1m0s
[19:54:14.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:66:0)
[19:54:14.274] Timer tick, asking Buildbucket for the build status
[19:54:14.326] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:14.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:67:0) after 1m0s
[19:55:14.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:67:0)
[19:55:14.353] Timer tick, asking Buildbucket for the build status
[19:55:14.405] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:14.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:68:0) after 1m0s
[19:56:14.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:68:0)
[19:56:14.432] Timer tick, asking Buildbucket for the build status
[19:56:14.528] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:14.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:69:0) after 1m0s
[19:57:14.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:69:0)
[19:57:14.515] Timer tick, asking Buildbucket for the build status
[19:57:14.552] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:14.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:70:0) after 1m0s
[19:58:14.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:70:0)
[19:58:14.583] Timer tick, asking Buildbucket for the build status
[19:58:14.651] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:14.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:71:0) after 1m0s
[19:59:14.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:71:0)
[19:59:14.674] Timer tick, asking Buildbucket for the build status
[19:59:14.915] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:14.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:72:0) after 1m0s
[20:00:14.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:72:0)
[20:00:14.920] Timer tick, asking Buildbucket for the build status
[20:00:15.034] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:15.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:73:0) after 1m0s
[20:01:15.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:73:0)
[20:01:15.048] Timer tick, asking Buildbucket for the build status
[20:01:15.496] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:15.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:74:0) after 1m0s
[20:02:15.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:74:0)
[20:02:15.485] Timer tick, asking Buildbucket for the build status
[20:02:15.567] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:15.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:75:0) after 1m0s
[20:03:15.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:75:0)
[20:03:15.583] Timer tick, asking Buildbucket for the build status
[20:03:15.632] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:15.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:76:0) after 1m0s
[20:04:15.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:76:0)
[20:04:15.654] Timer tick, asking Buildbucket for the build status
[20:04:15.758] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:15.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:77:0) after 1m0s
[20:05:15.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:77:0)
[20:05:15.793] Timer tick, asking Buildbucket for the build status
[20:05:15.908] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:15.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:78:0) after 1m0s
[20:06:15.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:78:0)
[20:06:15.912] Timer tick, asking Buildbucket for the build status
[20:06:15.962] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:15.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:79:0) after 1m0s
[20:07:15.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:79:0)
[20:07:15.934] Timer tick, asking Buildbucket for the build status
[20:07:15.984] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:15.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:80:0) after 1m0s
[20:08:16.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:80:0)
[20:08:16.033] Timer tick, asking Buildbucket for the build status
[20:08:16.096] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:16.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:81:0) after 1m0s
[20:09:16.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:81:0)
[20:09:16.073] Timer tick, asking Buildbucket for the build status
[20:09:16.184] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:16.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:82:0) after 1m0s
[20:10:16.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:82:0)
[20:10:16.161] Timer tick, asking Buildbucket for the build status
[20:10:16.272] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:16.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:83:0) after 1m0s
[20:11:16.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:83:0)
[20:11:16.405] Timer tick, asking Buildbucket for the build status
[20:11:16.458] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:16.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:84:0) after 1m0s
[20:12:16.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:84:0)
[20:12:16.480] Timer tick, asking Buildbucket for the build status
[20:12:16.608] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:16.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:85:0) after 1m0s
[20:13:16.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:85:0)
[20:13:16.595] Timer tick, asking Buildbucket for the build status
[20:13:16.695] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:16.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:86:0) after 1m0s
[20:14:16.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:86:0)
[20:14:16.730] Timer tick, asking Buildbucket for the build status
[20:14:16.804] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:16.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:87:0) after 1m0s
[20:15:16.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:87:0)
[20:15:16.819] Timer tick, asking Buildbucket for the build status
[20:15:16.860] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:16.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:88:0) after 1m0s
[20:16:16.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:88:0)
[20:16:16.860] Timer tick, asking Buildbucket for the build status
[20:16:16.932] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:16.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:89:0) after 1m0s
[20:17:16.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:89:0)
[20:17:16.948] Timer tick, asking Buildbucket for the build status
[20:17:17.308] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:17.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:90:0) after 1m0s
[20:18:17.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:90:0)
[20:18:17.342] Timer tick, asking Buildbucket for the build status
[20:18:17.400] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:17.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:91:0) after 1m0s
[20:19:17.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:91:0)
[20:19:17.384] Timer tick, asking Buildbucket for the build status
[20:19:17.428] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:17.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:92:0) after 1m0s
[20:20:17.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:92:0)
[20:20:17.433] Timer tick, asking Buildbucket for the build status
[20:20:17.581] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:17.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:93:0) after 1m0s
[20:21:17.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:93:0)
[20:21:17.565] Timer tick, asking Buildbucket for the build status
[20:21:17.796] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:17.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:94:0) after 1m0s
[20:22:17.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:94:0)
[20:22:17.804] Timer tick, asking Buildbucket for the build status
[20:22:17.860] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:17.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:95:0) after 1m0s
[20:23:17.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:95:0)
[20:23:17.908] Timer tick, asking Buildbucket for the build status
[20:23:17.990] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:17.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:96:0) after 1m0s
[20:24:18.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:96:0)
[20:24:18.047] Timer tick, asking Buildbucket for the build status
[20:24:18.118] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:18.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:97:0) after 1m0s
[20:25:18.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:97:0)
[20:25:18.186] Timer tick, asking Buildbucket for the build status
[20:25:18.250] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:18.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:98:0) after 1m0s
[20:26:18.260] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:98:0)
[20:26:18.260] Timer tick, asking Buildbucket for the build status
[20:26:18.311] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:18.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:99:0) after 1m0s
[20:27:18.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:99:0)
[20:27:18.337] Timer tick, asking Buildbucket for the build status
[20:27:18.385] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:18.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:100:0) after 1m0s
[20:28:18.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:100:0)
[20:28:18.423] Timer tick, asking Buildbucket for the build status
[20:28:18.589] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:18.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:101:0) after 1m0s
[20:29:18.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:101:0)
[20:29:18.597] Timer tick, asking Buildbucket for the build status
[20:29:18.759] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:18.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:102:0) after 1m0s
[20:30:18.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:102:0)
[20:30:18.762] Timer tick, asking Buildbucket for the build status
[20:30:18.807] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:18.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:103:0) after 1m0s
[20:31:18.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:103:0)
[20:31:18.829] Timer tick, asking Buildbucket for the build status
[20:31:18.894] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:18.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:104:0) after 1m0s
[20:32:18.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:104:0)
[20:32:18.920] Timer tick, asking Buildbucket for the build status
[20:32:18.959] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:18.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:105:0) after 1m0s
[20:33:18.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:105:0)
[20:33:18.962] Timer tick, asking Buildbucket for the build status
[20:33:19.069] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:19.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:106:0) after 1m0s
[20:34:19.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:106:0)
[20:34:19.096] Timer tick, asking Buildbucket for the build status
[20:34:19.165] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:107:0) after 1m0s
[20:35:19.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:107:0)
[20:35:19.204] Timer tick, asking Buildbucket for the build status
[20:35:19.363] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:19.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:108:0) after 1m0s
[20:36:19.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:108:0)
[20:36:19.365] Timer tick, asking Buildbucket for the build status
[20:36:19.418] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:19.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:109:0) after 1m0s
[20:37:19.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:109:0)
[20:37:19.430] Timer tick, asking Buildbucket for the build status
[20:37:19.471] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:19.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:110:0) after 1m0s
[20:38:19.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:110:0)
[20:38:19.714] Timer tick, asking Buildbucket for the build status
[20:38:19.785] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:19.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:111:0) after 1m0s
[20:39:19.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:111:0)
[20:39:19.814] Timer tick, asking Buildbucket for the build status
[20:39:19.932] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:19.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:112:0) after 1m0s
[20:40:29.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:112:0)
[20:40:29.426] Timer tick, asking Buildbucket for the build status
[20:40:29.475] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:29.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:113:0) after 1m0s
[20:41:29.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:113:0)
[20:41:29.533] Timer tick, asking Buildbucket for the build status
[20:41:29.570] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:29.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:114:0) after 1m0s
[20:42:29.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:114:0)
[20:42:29.687] Timer tick, asking Buildbucket for the build status
[20:42:29.726] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:29.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:115:0) after 1m0s
[20:43:29.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:115:0)
[20:43:29.835] Timer tick, asking Buildbucket for the build status
[20:43:29.880] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:29.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:116:0) after 1m0s
[20:44:30.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:116:0)
[20:44:30.086] Timer tick, asking Buildbucket for the build status
[20:44:30.134] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:30.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:117:0) after 1m0s
[20:45:30.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:117:0)
[20:45:30.282] Timer tick, asking Buildbucket for the build status
[20:45:30.340] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:30.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:118:0) after 1m0s
[20:46:30.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:118:0)
[20:46:30.368] Timer tick, asking Buildbucket for the build status
[20:46:30.415] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:30.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:119:0) after 1m0s
[20:47:30.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:119:0)
[20:47:30.808] Timer tick, asking Buildbucket for the build status
[20:47:30.867] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:30.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:120:0) after 1m0s
[20:48:30.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:120:0)
[20:48:30.925] Timer tick, asking Buildbucket for the build status
[20:48:30.970] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:30.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:121:0) after 1m0s
[20:49:31.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:121:0)
[20:49:31.006] Timer tick, asking Buildbucket for the build status
[20:49:31.102] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:31.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:122:0) after 1m0s
[20:50:38.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:122:0)
[20:50:38.831] Timer tick, asking Buildbucket for the build status
[20:50:38.926] Build 8915271257269632608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:38.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080701319412589136:123:0) after 1m0s
[20:51:00.379] Received PubSub notification, asking Buildbucket for the build status
[20:51:00.571] Build 8915271257269632608: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:51:00.571] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556139060066969",
"created_by": "project:fuchsia",
"created_ts": "1556131800355382",
"failure_reason": "BUILD_FAILURE",
"id": "8915271257269632608",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6e619075466d46de32b2c1ad0c71c67c81c1281f\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-nwzw\", \"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\": \"6e619075466d46de32b2c1ad0c71c67c81c1281f\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-nwzw\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4274-a0a5f8c\"], \"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": "1556131839054502",
"status": "COMPLETED",
"status_changed_ts": "1556131839815164",
"tags": [
"builder:clang",
"buildset:commit/git/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6e619075466d46de32b2c1ad0c71c67c81c1281f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080701319412589136",
"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/8915271257269632608/+/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:44698073ef28df10",
"user_agent:luci-scheduler"
],
"updated_ts": "1556139060120580",
"url": "https://ci.chromium.org/b/8915271257269632608",
"utcnow_ts": "1556139060546039"
}
}
[20:51:00.571] Invocation finished in 2h1m1.644359411s with status FAILED