[22:44:24.149] New invocation is queued and will start shortly
[22:44:25.192] Starting the invocation (attempt 1)
[22:44:25.231] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:44:25.231] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:44:25.231] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083223286033829600",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"704817912a2d7ea3c95b5194480c4c62b789043d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083223286033829600",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/704817912a2d7ea3c95b5194480c4c62b789043d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/704817912a2d7ea3c95b5194480c4c62b789043d",
"gitiles_ref:refs/heads/master"
]
}
[22:44:26.159] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553726665346116",
"id": "8917793224116282896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"704817912a2d7ea3c95b5194480c4c62b789043d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553726665673215",
"tags": [
"builder:clang",
"buildset:commit/git/704817912a2d7ea3c95b5194480c4c62b789043d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/704817912a2d7ea3c95b5194480c4c62b789043d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083223286033829600",
"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/8917793224116282896/+/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": "1553726665673366",
"url": "https://ci.chromium.org/b/8917793224116282896",
"utcnow_ts": "1553726666148445"
}
}
[22:44:26.159] Task URL: https://ci.chromium.org/b/8917793224116282896
[22:44:26.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:2:0) after 1m0s
[22:44:34.396] Received PubSub notification, asking Buildbucket for the build status
[22:44:34.658] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:26.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:2:0)
[22:45:26.178] Timer tick, asking Buildbucket for the build status
[22:45:26.627] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:26.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:4:0) after 1m0s
[22:46:26.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:4:0)
[22:46:26.636] Timer tick, asking Buildbucket for the build status
[22:46:26.720] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:26.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:5:0) after 1m0s
[22:47:26.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:5:0)
[22:47:26.731] Timer tick, asking Buildbucket for the build status
[22:47:26.800] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:6:0) after 1m0s
[22:48:26.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:6:0)
[22:48:26.808] Timer tick, asking Buildbucket for the build status
[22:48:26.864] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:26.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:7:0) after 1m0s
[22:49:26.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:7:0)
[22:49:26.865] Timer tick, asking Buildbucket for the build status
[22:49:26.990] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:26.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:8:0) after 1m0s
[22:50:27.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:8:0)
[22:50:27.009] Timer tick, asking Buildbucket for the build status
[22:50:27.091] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:27.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:9:0) after 1m0s
[22:51:27.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:9:0)
[22:51:27.098] Timer tick, asking Buildbucket for the build status
[22:51:27.213] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:27.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:10:0) after 1m0s
[22:52:27.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:10:0)
[22:52:27.216] Timer tick, asking Buildbucket for the build status
[22:52:27.338] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:27.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:11:0) after 1m0s
[22:53:27.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:11:0)
[22:53:27.359] Timer tick, asking Buildbucket for the build status
[22:53:27.544] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:27.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:12:0) after 1m0s
[22:54:27.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:12:0)
[22:54:27.564] Timer tick, asking Buildbucket for the build status
[22:54:27.615] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:27.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:13:0) after 1m0s
[22:55:27.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:13:0)
[22:55:27.618] Timer tick, asking Buildbucket for the build status
[22:55:27.677] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:27.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:14:0) after 1m0s
[22:56:27.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:14:0)
[22:56:27.699] Timer tick, asking Buildbucket for the build status
[22:56:27.824] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:27.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:15:0) after 1m0s
[22:57:27.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:15:0)
[22:57:27.841] Timer tick, asking Buildbucket for the build status
[22:57:27.963] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:27.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:16:0) after 1m0s
[22:58:27.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:16:0)
[22:58:27.993] Timer tick, asking Buildbucket for the build status
[22:58:28.154] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:28.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:17:0) after 1m0s
[22:59:28.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:17:0)
[22:59:28.131] Timer tick, asking Buildbucket for the build status
[22:59:28.276] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:28.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:18:0) after 1m0s
[23:00:28.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:18:0)
[23:00:28.289] Timer tick, asking Buildbucket for the build status
[23:00:28.379] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:28.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:19:0) after 1m0s
[23:01:28.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:19:0)
[23:01:28.407] Timer tick, asking Buildbucket for the build status
[23:01:28.450] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:28.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:20:0) after 1m0s
[23:02:28.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:20:0)
[23:02:28.471] Timer tick, asking Buildbucket for the build status
[23:02:28.590] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:28.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:21:0) after 1m0s
[23:03:28.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:21:0)
[23:03:28.580] Timer tick, asking Buildbucket for the build status
[23:03:28.692] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:28.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:22:0) after 1m0s
[23:04:28.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:22:0)
[23:04:28.711] Timer tick, asking Buildbucket for the build status
[23:04:28.752] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:28.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:23:0) after 1m0s
[23:05:28.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:23:0)
[23:05:28.772] Timer tick, asking Buildbucket for the build status
[23:05:28.830] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:28.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:24:0) after 1m0s
[23:06:28.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:24:0)
[23:06:28.849] Timer tick, asking Buildbucket for the build status
[23:06:28.974] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:28.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:25:0) after 1m0s
[23:07:28.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:25:0)
[23:07:28.993] Timer tick, asking Buildbucket for the build status
[23:07:29.182] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:29.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:26:0) after 1m0s
[23:08:29.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:26:0)
[23:08:29.168] Timer tick, asking Buildbucket for the build status
[23:08:29.426] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:29.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:27:0) after 1m0s
[23:09:29.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:27:0)
[23:09:29.445] Timer tick, asking Buildbucket for the build status
[23:09:29.494] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:29.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:28:0) after 1m0s
[23:10:29.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:28:0)
[23:10:29.536] Timer tick, asking Buildbucket for the build status
[23:10:29.681] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:29.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:29:0) after 1m0s
[23:11:29.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:29:0)
[23:11:29.703] Timer tick, asking Buildbucket for the build status
[23:11:29.819] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:29.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:30:0) after 1m0s
[23:12:29.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:30:0)
[23:12:29.839] Timer tick, asking Buildbucket for the build status
[23:12:29.943] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:29.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:31:0) after 1m0s
[23:13:29.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:31:0)
[23:13:29.964] Timer tick, asking Buildbucket for the build status
[23:13:30.191] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:30.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:32:0) after 1m0s
[23:14:30.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:32:0)
[23:14:30.218] Timer tick, asking Buildbucket for the build status
[23:14:30.278] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:30.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:33:0) after 1m0s
[23:15:30.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:33:0)
[23:15:30.273] Timer tick, asking Buildbucket for the build status
[23:15:30.341] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:30.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:34:0) after 1m0s
[23:16:30.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:34:0)
[23:16:30.360] Timer tick, asking Buildbucket for the build status
[23:16:30.397] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:30.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:35:0) after 1m0s
[23:17:30.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:35:0)
[23:17:30.416] Timer tick, asking Buildbucket for the build status
[23:17:30.529] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:30.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:36:0) after 1m0s
[23:18:30.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:36:0)
[23:18:30.502] Timer tick, asking Buildbucket for the build status
[23:18:30.595] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:30.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:37:0) after 1m0s
[23:19:30.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:37:0)
[23:19:30.618] Timer tick, asking Buildbucket for the build status
[23:19:30.733] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:30.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:38:0) after 1m0s
[23:20:30.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:38:0)
[23:20:30.756] Timer tick, asking Buildbucket for the build status
[23:20:30.821] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:30.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:39:0) after 1m0s
[23:21:30.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:39:0)
[23:21:30.826] Timer tick, asking Buildbucket for the build status
[23:21:30.873] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:30.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:40:0) after 1m0s
[23:22:30.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:40:0)
[23:22:30.896] Timer tick, asking Buildbucket for the build status
[23:22:30.965] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:30.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:41:0) after 1m0s
[23:23:30.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:41:0)
[23:23:30.986] Timer tick, asking Buildbucket for the build status
[23:23:31.207] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:31.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:42:0) after 1m0s
[23:24:31.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:42:0)
[23:24:31.228] Timer tick, asking Buildbucket for the build status
[23:24:31.306] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:31.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:43:0) after 1m0s
[23:25:31.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:43:0)
[23:25:31.379] Timer tick, asking Buildbucket for the build status
[23:25:31.443] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:31.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:44:0) after 1m0s
[23:26:31.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:44:0)
[23:26:31.475] Timer tick, asking Buildbucket for the build status
[23:26:31.726] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:31.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:45:0) after 1m0s
[23:27:31.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:45:0)
[23:27:31.766] Timer tick, asking Buildbucket for the build status
[23:27:31.894] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:31.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:46:0) after 1m0s
[23:28:31.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:46:0)
[23:28:31.897] Timer tick, asking Buildbucket for the build status
[23:28:31.972] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:31.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:47:0) after 1m0s
[23:29:31.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:47:0)
[23:29:31.990] Timer tick, asking Buildbucket for the build status
[23:29:32.032] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:32.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:48:0) after 1m0s
[23:30:32.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:48:0)
[23:30:32.050] Timer tick, asking Buildbucket for the build status
[23:30:32.504] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:32.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:49:0) after 1m0s
[23:31:32.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:49:0)
[23:31:32.524] Timer tick, asking Buildbucket for the build status
[23:31:32.584] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:32.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:50:0) after 1m0s
[23:32:32.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:50:0)
[23:32:32.637] Timer tick, asking Buildbucket for the build status
[23:32:32.686] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:32.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:51:0) after 1m0s
[23:33:32.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:51:0)
[23:33:32.657] Timer tick, asking Buildbucket for the build status
[23:33:32.748] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:32.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:52:0) after 1m0s
[23:34:32.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:52:0)
[23:34:32.771] Timer tick, asking Buildbucket for the build status
[23:34:33.027] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:33.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:53:0) after 1m0s
[23:35:33.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:53:0)
[23:35:33.091] Timer tick, asking Buildbucket for the build status
[23:35:33.190] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:33.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:54:0) after 1m0s
[23:36:33.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:54:0)
[23:36:33.199] Timer tick, asking Buildbucket for the build status
[23:36:33.287] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:33.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:55:0) after 1m0s
[23:37:33.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:55:0)
[23:37:33.264] Timer tick, asking Buildbucket for the build status
[23:37:33.314] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:33.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:56:0) after 1m0s
[23:38:33.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:56:0)
[23:38:33.297] Timer tick, asking Buildbucket for the build status
[23:38:33.359] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:33.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:57:0) after 1m0s
[23:39:33.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:57:0)
[23:39:33.370] Timer tick, asking Buildbucket for the build status
[23:39:33.522] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:33.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:58:0) after 1m0s
[23:40:33.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:58:0)
[23:40:33.547] Timer tick, asking Buildbucket for the build status
[23:40:33.598] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:33.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:59:0) after 1m0s
[23:41:33.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:59:0)
[23:41:33.643] Timer tick, asking Buildbucket for the build status
[23:41:33.693] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:33.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:60:0) after 1m0s
[23:42:33.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:60:0)
[23:42:33.682] Timer tick, asking Buildbucket for the build status
[23:42:33.738] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:33.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:61:0) after 1m0s
[23:43:33.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:61:0)
[23:43:33.761] Timer tick, asking Buildbucket for the build status
[23:43:33.976] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:33.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:62:0) after 1m0s
[23:44:33.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:62:0)
[23:44:33.997] Timer tick, asking Buildbucket for the build status
[23:44:34.055] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:34.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:63:0) after 1m0s
[23:45:34.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:63:0)
[23:45:34.057] Timer tick, asking Buildbucket for the build status
[23:45:34.117] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:34.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:64:0) after 1m0s
[23:46:34.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:64:0)
[23:46:34.144] Timer tick, asking Buildbucket for the build status
[23:46:34.193] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:34.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:65:0) after 1m0s
[23:47:34.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:65:0)
[23:47:34.163] Timer tick, asking Buildbucket for the build status
[23:47:34.295] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:34.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:66:0) after 1m0s
[23:48:34.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:66:0)
[23:48:34.321] Timer tick, asking Buildbucket for the build status
[23:48:34.369] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:34.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:67:0) after 1m0s
[23:49:34.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:67:0)
[23:49:34.389] Timer tick, asking Buildbucket for the build status
[23:49:34.433] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:34.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:68:0) after 1m0s
[23:50:34.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:68:0)
[23:50:34.454] Timer tick, asking Buildbucket for the build status
[23:50:34.503] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:34.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:69:0) after 1m0s
[23:51:34.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:69:0)
[23:51:34.538] Timer tick, asking Buildbucket for the build status
[23:51:34.600] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:34.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:70:0) after 1m0s
[23:52:34.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:70:0)
[23:52:34.623] Timer tick, asking Buildbucket for the build status
[23:52:34.791] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:34.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:71:0) after 1m0s
[23:53:34.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:71:0)
[23:53:34.804] Timer tick, asking Buildbucket for the build status
[23:53:34.883] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:34.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:72:0) after 1m0s
[23:54:34.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:72:0)
[23:54:34.892] Timer tick, asking Buildbucket for the build status
[23:54:35.122] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:35.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:73:0) after 1m0s
[23:55:35.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:73:0)
[23:55:35.142] Timer tick, asking Buildbucket for the build status
[23:55:35.233] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:35.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:74:0) after 1m0s
[23:56:35.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:74:0)
[23:56:35.255] Timer tick, asking Buildbucket for the build status
[23:56:35.293] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:35.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:75:0) after 1m0s
[23:57:35.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:75:0)
[23:57:35.334] Timer tick, asking Buildbucket for the build status
[23:57:35.568] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:35.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:76:0) after 1m0s
[23:58:35.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:76:0)
[23:58:35.563] Timer tick, asking Buildbucket for the build status
[23:58:35.682] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:35.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:77:0) after 1m0s
[23:59:35.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:77:0)
[23:59:35.726] Timer tick, asking Buildbucket for the build status
[23:59:35.782] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:35.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:78:0) after 1m0s
[00:00:35.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:78:0)
[00:00:35.803] Timer tick, asking Buildbucket for the build status
[00:00:35.883] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:35.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:79:0) after 1m0s
[00:01:35.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:79:0)
[00:01:35.904] Timer tick, asking Buildbucket for the build status
[00:01:35.943] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:35.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:80:0) after 1m0s
[00:02:36.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:80:0)
[00:02:36.019] Timer tick, asking Buildbucket for the build status
[00:02:36.079] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:36.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:81:0) after 1m0s
[00:03:36.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:81:0)
[00:03:36.103] Timer tick, asking Buildbucket for the build status
[00:03:36.174] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:36.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:82:0) after 1m0s
[00:04:36.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:82:0)
[00:04:36.171] Timer tick, asking Buildbucket for the build status
[00:04:36.521] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:36.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:83:0) after 1m0s
[00:05:36.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:83:0)
[00:05:36.534] Timer tick, asking Buildbucket for the build status
[00:05:36.604] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:36.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:84:0) after 1m0s
[00:06:36.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:84:0)
[00:06:36.580] Timer tick, asking Buildbucket for the build status
[00:06:36.667] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:36.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:85:0) after 1m0s
[00:07:36.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:85:0)
[00:07:36.690] Timer tick, asking Buildbucket for the build status
[00:07:36.805] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:36.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:86:0) after 1m0s
[00:08:36.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:86:0)
[00:08:36.780] Timer tick, asking Buildbucket for the build status
[00:08:36.987] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:87:0) after 1m0s
[00:09:37.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:87:0)
[00:09:37.007] Timer tick, asking Buildbucket for the build status
[00:09:37.097] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:37.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:88:0) after 1m0s
[00:10:37.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:88:0)
[00:10:37.067] Timer tick, asking Buildbucket for the build status
[00:10:37.182] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:37.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:89:0) after 1m0s
[00:11:37.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:89:0)
[00:11:37.277] Timer tick, asking Buildbucket for the build status
[00:11:37.418] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:37.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:90:0) after 1m0s
[00:12:37.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:90:0)
[00:12:37.431] Timer tick, asking Buildbucket for the build status
[00:12:37.491] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:37.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:91:0) after 1m0s
[00:13:37.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:91:0)
[00:13:37.519] Timer tick, asking Buildbucket for the build status
[00:13:37.569] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:37.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:92:0) after 1m0s
[00:14:37.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:92:0)
[00:14:37.593] Timer tick, asking Buildbucket for the build status
[00:14:37.683] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:37.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:93:0) after 1m0s
[00:15:37.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:93:0)
[00:15:37.708] Timer tick, asking Buildbucket for the build status
[00:15:37.750] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:37.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:94:0) after 1m0s
[00:16:37.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:94:0)
[00:16:37.771] Timer tick, asking Buildbucket for the build status
[00:16:37.937] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:37.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:95:0) after 1m0s
[00:17:37.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:95:0)
[00:17:37.959] Timer tick, asking Buildbucket for the build status
[00:17:38.186] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:38.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:96:0) after 1m0s
[00:18:38.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:96:0)
[00:18:38.217] Timer tick, asking Buildbucket for the build status
[00:18:38.266] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:38.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:97:0) after 1m0s
[00:19:38.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:97:0)
[00:19:38.289] Timer tick, asking Buildbucket for the build status
[00:19:38.332] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:38.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:98:0) after 1m0s
[00:20:38.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:98:0)
[00:20:38.353] Timer tick, asking Buildbucket for the build status
[00:20:38.422] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:38.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:99:0) after 1m0s
[00:21:38.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:99:0)
[00:21:38.446] Timer tick, asking Buildbucket for the build status
[00:21:38.557] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:38.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:100:0) after 1m0s
[00:22:38.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:100:0)
[00:22:38.578] Timer tick, asking Buildbucket for the build status
[00:22:38.618] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:38.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:101:0) after 1m0s
[00:23:38.634] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:101:0)
[00:23:38.634] Timer tick, asking Buildbucket for the build status
[00:23:38.695] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:38.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:102:0) after 1m0s
[00:24:38.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:102:0)
[00:24:38.719] Timer tick, asking Buildbucket for the build status
[00:24:38.786] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:38.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:103:0) after 1m0s
[00:25:38.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:103:0)
[00:25:38.850] Timer tick, asking Buildbucket for the build status
[00:25:38.902] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:38.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:104:0) after 1m0s
[00:26:38.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:104:0)
[00:26:38.922] Timer tick, asking Buildbucket for the build status
[00:26:38.962] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:38.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:105:0) after 1m0s
[00:27:38.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:105:0)
[00:27:38.968] Timer tick, asking Buildbucket for the build status
[00:27:39.077] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:39.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:106:0) after 1m0s
[00:28:39.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:106:0)
[00:28:39.084] Timer tick, asking Buildbucket for the build status
[00:28:39.243] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:39.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:107:0) after 1m0s
[00:29:39.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:107:0)
[00:29:39.320] Timer tick, asking Buildbucket for the build status
[00:29:39.424] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:39.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:108:0) after 1m0s
[00:30:39.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:108:0)
[00:30:39.454] Timer tick, asking Buildbucket for the build status
[00:30:39.540] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:39.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:109:0) after 1m0s
[00:31:39.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:109:0)
[00:31:39.564] Timer tick, asking Buildbucket for the build status
[00:31:39.865] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:39.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:110:0) after 1m0s
[00:32:39.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:110:0)
[00:32:39.894] Timer tick, asking Buildbucket for the build status
[00:32:39.971] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:39.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:111:0) after 1m0s
[00:33:39.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:111:0)
[00:33:39.993] Timer tick, asking Buildbucket for the build status
[00:33:40.043] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:40.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:112:0) after 1m0s
[00:34:40.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:112:0)
[00:34:40.056] Timer tick, asking Buildbucket for the build status
[00:34:40.156] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:40.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:113:0) after 1m0s
[00:35:40.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:113:0)
[00:35:40.158] Timer tick, asking Buildbucket for the build status
[00:35:40.200] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:40.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:114:0) after 1m0s
[00:36:40.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:114:0)
[00:36:40.197] Timer tick, asking Buildbucket for the build status
[00:36:40.253] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:40.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:115:0) after 1m0s
[00:37:40.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:115:0)
[00:37:40.281] Timer tick, asking Buildbucket for the build status
[00:37:40.328] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:40.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:116:0) after 1m0s
[00:38:40.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:116:0)
[00:38:40.356] Timer tick, asking Buildbucket for the build status
[00:38:40.402] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:40.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:117:0) after 1m0s
[00:39:40.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:117:0)
[00:39:40.380] Timer tick, asking Buildbucket for the build status
[00:39:40.452] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:40.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:118:0) after 1m0s
[00:40:40.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:118:0)
[00:40:40.474] Timer tick, asking Buildbucket for the build status
[00:40:40.520] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:40.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:119:0) after 1m0s
[00:41:40.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:119:0)
[00:41:40.521] Timer tick, asking Buildbucket for the build status
[00:41:40.582] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:40.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:120:0) after 1m0s
[00:42:40.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:120:0)
[00:42:40.605] Timer tick, asking Buildbucket for the build status
[00:42:40.646] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:40.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:121:0) after 1m0s
[00:43:40.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:121:0)
[00:43:40.701] Timer tick, asking Buildbucket for the build status
[00:43:40.822] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:40.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:122:0) after 1m0s
[00:44:40.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:122:0)
[00:44:40.841] Timer tick, asking Buildbucket for the build status
[00:44:40.961] Build 8917793224116282896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083223286033829600:123:0) after 1m0s
[00:45:03.017] Received PubSub notification, asking Buildbucket for the build status
[00:45:03.123] Build 8917793224116282896: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:45:03.123] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553733902535558",
"created_by": "project:fuchsia",
"created_ts": "1553726665346116",
"failure_reason": "BUILD_FAILURE",
"id": "8917793224116282896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"704817912a2d7ea3c95b5194480c4c62b789043d\"}}",
"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-7887e815-us-central1-b-tpbp\", \"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\": \"704817912a2d7ea3c95b5194480c4c62b789043d\", \"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-7887e815-us-central1-b-tpbp\"], \"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\": [\"4221-9c50278\"], \"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": "1553726673809523",
"status": "COMPLETED",
"status_changed_ts": "1553726674077861",
"tags": [
"builder:clang",
"buildset:commit/git/704817912a2d7ea3c95b5194480c4c62b789043d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/704817912a2d7ea3c95b5194480c4c62b789043d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083223286033829600",
"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/8917793224116282896/+/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:43da24ffa9a57710",
"user_agent:luci-scheduler"
],
"updated_ts": "1553733902766727",
"url": "https://ci.chromium.org/b/8917793224116282896",
"utcnow_ts": "1553733903111849"
}
}
[00:45:03.123] Invocation finished in 2h0m38.988982726s with status FAILED