[11:38:04.652] New invocation is queued and will start shortly
[11:38:05.760] Starting the invocation (attempt 1)
[11:38:05.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:38:05.794] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:38:05.794] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078554165325469488",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"4c146ab8c4649d3cfcbc17643f8388cb525bdd56\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078554165325469488",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"gitiles_ref:refs/heads/master"
]
}
[11:38:06.974] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558179485925971",
"id": "8913124103324543728",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4c146ab8c4649d3cfcbc17643f8388cb525bdd56\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558179486787831",
"tags": [
"builder:clang",
"buildset:commit/git/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078554165325469488",
"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/8913124103324543728/+/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": "1558179486788007",
"url": "https://ci.chromium.org/b/8913124103324543728",
"utcnow_ts": "1558179486966826"
}
}
[11:38:06.974] Task URL: https://ci.chromium.org/b/8913124103324543728
[11:38:06.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:2:0) after 1m0s
[11:38:43.983] Received PubSub notification, asking Buildbucket for the build status
[11:38:44.063] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:06.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:2:0)
[11:39:06.994] Timer tick, asking Buildbucket for the build status
[11:39:07.178] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:07.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:4:0) after 1m0s
[11:40:07.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:4:0)
[11:40:07.201] Timer tick, asking Buildbucket for the build status
[11:40:07.940] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:07.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:5:0) after 1m0s
[11:41:07.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:5:0)
[11:41:07.957] Timer tick, asking Buildbucket for the build status
[11:41:08.065] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:08.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:6:0) after 1m0s
[11:42:08.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:6:0)
[11:42:08.133] Timer tick, asking Buildbucket for the build status
[11:42:08.281] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:08.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:7:0) after 1m0s
[11:43:08.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:7:0)
[11:43:08.303] Timer tick, asking Buildbucket for the build status
[11:43:08.350] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:08.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:8:0) after 1m0s
[11:44:08.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:8:0)
[11:44:08.375] Timer tick, asking Buildbucket for the build status
[11:44:08.444] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:08.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:9:0) after 1m0s
[11:45:08.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:9:0)
[11:45:08.463] Timer tick, asking Buildbucket for the build status
[11:45:08.511] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:08.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:10:0) after 1m0s
[11:46:08.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:10:0)
[11:46:08.548] Timer tick, asking Buildbucket for the build status
[11:46:08.630] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:08.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:11:0) after 1m0s
[11:47:08.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:11:0)
[11:47:08.661] Timer tick, asking Buildbucket for the build status
[11:47:08.754] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:08.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:12:0) after 1m0s
[11:48:09.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:12:0)
[11:48:09.044] Timer tick, asking Buildbucket for the build status
[11:48:09.309] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:09.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:13:0) after 1m0s
[11:49:09.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:13:0)
[11:49:09.292] Timer tick, asking Buildbucket for the build status
[11:49:09.583] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:09.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:14:0) after 1m0s
[11:50:09.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:14:0)
[11:50:09.610] Timer tick, asking Buildbucket for the build status
[11:50:09.776] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:09.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:15:0) after 1m0s
[11:51:09.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:15:0)
[11:51:09.794] Timer tick, asking Buildbucket for the build status
[11:51:09.868] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:09.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:16:0) after 1m0s
[11:52:09.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:16:0)
[11:52:09.901] Timer tick, asking Buildbucket for the build status
[11:52:09.959] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:09.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:17:0) after 1m0s
[11:53:10.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:17:0)
[11:53:10.026] Timer tick, asking Buildbucket for the build status
[11:53:10.275] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:10.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:18:0) after 1m0s
[11:54:10.322] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:18:0)
[11:54:10.322] Timer tick, asking Buildbucket for the build status
[11:54:11.541] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:11.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:19:0) after 1m0s
[11:55:11.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:19:0)
[11:55:11.526] Timer tick, asking Buildbucket for the build status
[11:55:11.679] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:11.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:20:0) after 1m0s
[11:56:11.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:20:0)
[11:56:11.671] Timer tick, asking Buildbucket for the build status
[11:56:11.824] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:11.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:21:0) after 1m0s
[11:57:11.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:21:0)
[11:57:11.846] Timer tick, asking Buildbucket for the build status
[11:57:11.935] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:11.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:22:0) after 1m0s
[11:58:11.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:22:0)
[11:58:11.986] Timer tick, asking Buildbucket for the build status
[11:58:12.104] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:12.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:23:0) after 1m0s
[11:59:12.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:23:0)
[11:59:12.171] Timer tick, asking Buildbucket for the build status
[11:59:12.265] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:12.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:24:0) after 1m0s
[12:00:12.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:24:0)
[12:00:12.276] Timer tick, asking Buildbucket for the build status
[12:00:12.350] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:12.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:25:0) after 1m0s
[12:01:12.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:25:0)
[12:01:12.369] Timer tick, asking Buildbucket for the build status
[12:01:12.449] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:12.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:26:0) after 1m0s
[12:02:12.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:26:0)
[12:02:12.510] Timer tick, asking Buildbucket for the build status
[12:02:12.588] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:12.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:27:0) after 1m0s
[12:03:12.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:27:0)
[12:03:12.592] Timer tick, asking Buildbucket for the build status
[12:03:12.922] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:12.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:28:0) after 1m0s
[12:04:13.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:28:0)
[12:04:13.006] Timer tick, asking Buildbucket for the build status
[12:04:13.213] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:13.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:29:0) after 1m0s
[12:05:13.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:29:0)
[12:05:13.269] Timer tick, asking Buildbucket for the build status
[12:05:13.683] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:13.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:30:0) after 1m0s
[12:06:13.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:30:0)
[12:06:13.701] Timer tick, asking Buildbucket for the build status
[12:06:13.812] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:13.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:31:0) after 1m0s
[12:07:13.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:31:0)
[12:07:13.854] Timer tick, asking Buildbucket for the build status
[12:07:14.040] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:14.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:32:0) after 1m0s
[12:08:14.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:32:0)
[12:08:14.111] Timer tick, asking Buildbucket for the build status
[12:08:14.254] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:14.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:33:0) after 1m0s
[12:09:14.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:33:0)
[12:09:14.786] Timer tick, asking Buildbucket for the build status
[12:09:15.241] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:15.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:34:0) after 1m0s
[12:10:15.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:34:0)
[12:10:15.305] Timer tick, asking Buildbucket for the build status
[12:10:15.467] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:15.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:35:0) after 1m0s
[12:11:15.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:35:0)
[12:11:15.546] Timer tick, asking Buildbucket for the build status
[12:11:15.767] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:15.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:36:0) after 1m0s
[12:12:15.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:36:0)
[12:12:15.849] Timer tick, asking Buildbucket for the build status
[12:12:15.933] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:15.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:37:0) after 1m0s
[12:13:15.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:37:0)
[12:13:15.996] Timer tick, asking Buildbucket for the build status
[12:13:16.176] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:16.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:38:0) after 1m0s
[12:14:16.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:38:0)
[12:14:16.185] Timer tick, asking Buildbucket for the build status
[12:14:16.396] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:16.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:39:0) after 1m0s
[12:15:16.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:39:0)
[12:15:16.434] Timer tick, asking Buildbucket for the build status
[12:15:16.535] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:16.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:40:0) after 1m0s
[12:16:16.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:40:0)
[12:16:16.586] Timer tick, asking Buildbucket for the build status
[12:16:16.812] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:16.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:41:0) after 1m0s
[12:17:16.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:41:0)
[12:17:16.830] Timer tick, asking Buildbucket for the build status
[12:17:16.880] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:16.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:42:0) after 1m0s
[12:18:16.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:42:0)
[12:18:16.928] Timer tick, asking Buildbucket for the build status
[12:18:16.986] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:43:0) after 1m0s
[12:19:17.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:43:0)
[12:19:17.018] Timer tick, asking Buildbucket for the build status
[12:19:17.073] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:17.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:44:0) after 1m0s
[12:20:17.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:44:0)
[12:20:17.094] Timer tick, asking Buildbucket for the build status
[12:20:17.264] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:17.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:45:0) after 1m0s
[12:21:17.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:45:0)
[12:21:17.294] Timer tick, asking Buildbucket for the build status
[12:21:17.463] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:17.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:46:0) after 1m0s
[12:22:17.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:46:0)
[12:22:17.488] Timer tick, asking Buildbucket for the build status
[12:22:17.539] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:17.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:47:0) after 1m0s
[12:23:17.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:47:0)
[12:23:17.622] Timer tick, asking Buildbucket for the build status
[12:23:18.206] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:18.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:48:0) after 1m0s
[12:24:18.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:48:0)
[12:24:18.265] Timer tick, asking Buildbucket for the build status
[12:24:18.391] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:18.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:49:0) after 1m0s
[12:25:18.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:49:0)
[12:25:18.434] Timer tick, asking Buildbucket for the build status
[12:25:19.176] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:19.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:50:0) after 1m0s
[12:26:19.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:50:0)
[12:26:19.213] Timer tick, asking Buildbucket for the build status
[12:26:19.455] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:19.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:51:0) after 1m0s
[12:27:19.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:51:0)
[12:27:19.474] Timer tick, asking Buildbucket for the build status
[12:27:19.531] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:19.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:52:0) after 1m0s
[12:28:19.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:52:0)
[12:28:19.630] Timer tick, asking Buildbucket for the build status
[12:28:19.778] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:19.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:53:0) after 1m0s
[12:29:19.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:53:0)
[12:29:19.860] Timer tick, asking Buildbucket for the build status
[12:29:19.915] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:19.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:54:0) after 1m0s
[12:30:19.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:54:0)
[12:30:19.948] Timer tick, asking Buildbucket for the build status
[12:30:19.998] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:19.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:55:0) after 1m0s
[12:31:20.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:55:0)
[12:31:20.035] Timer tick, asking Buildbucket for the build status
[12:31:20.111] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:20.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:56:0) after 1m0s
[12:32:20.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:56:0)
[12:32:20.158] Timer tick, asking Buildbucket for the build status
[12:32:20.232] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:20.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:57:0) after 1m0s
[12:33:20.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:57:0)
[12:33:20.261] Timer tick, asking Buildbucket for the build status
[12:33:20.360] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:20.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:58:0) after 1m0s
[12:34:20.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:58:0)
[12:34:20.386] Timer tick, asking Buildbucket for the build status
[12:34:20.436] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:20.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:59:0) after 1m0s
[12:35:20.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:59:0)
[12:35:20.472] Timer tick, asking Buildbucket for the build status
[12:35:20.551] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:20.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:60:0) after 1m0s
[12:36:20.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:60:0)
[12:36:20.569] Timer tick, asking Buildbucket for the build status
[12:36:20.720] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:20.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:61:0) after 1m0s
[12:37:20.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:61:0)
[12:37:20.702] Timer tick, asking Buildbucket for the build status
[12:37:21.473] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:21.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:62:0) after 1m0s
[12:38:21.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:62:0)
[12:38:21.555] Timer tick, asking Buildbucket for the build status
[12:38:21.673] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:21.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:63:0) after 1m0s
[12:39:21.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:63:0)
[12:39:21.678] Timer tick, asking Buildbucket for the build status
[12:39:21.800] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:21.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:64:0) after 1m0s
[12:40:21.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:64:0)
[12:40:21.832] Timer tick, asking Buildbucket for the build status
[12:40:22.495] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:22.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:65:0) after 1m0s
[12:41:22.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:65:0)
[12:41:22.531] Timer tick, asking Buildbucket for the build status
[12:41:22.586] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:22.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:66:0) after 1m0s
[12:42:22.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:66:0)
[12:42:22.620] Timer tick, asking Buildbucket for the build status
[12:42:22.664] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:22.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:67:0) after 1m0s
[12:43:22.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:67:0)
[12:43:22.695] Timer tick, asking Buildbucket for the build status
[12:43:22.946] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:22.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:68:0) after 1m0s
[12:44:23.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:68:0)
[12:44:23.027] Timer tick, asking Buildbucket for the build status
[12:44:23.089] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:23.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:69:0) after 1m0s
[12:45:23.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:69:0)
[12:45:23.159] Timer tick, asking Buildbucket for the build status
[12:45:23.527] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:23.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:70:0) after 1m0s
[12:46:23.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:70:0)
[12:46:23.535] Timer tick, asking Buildbucket for the build status
[12:46:23.690] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:23.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:71:0) after 1m0s
[12:47:23.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:71:0)
[12:47:23.784] Timer tick, asking Buildbucket for the build status
[12:47:24.007] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:24.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:72:0) after 1m0s
[12:48:26.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:72:0)
[12:48:26.948] Timer tick, asking Buildbucket for the build status
[12:48:27.002] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:27.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:73:0) after 1m0s
[12:49:27.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:73:0)
[12:49:27.027] Timer tick, asking Buildbucket for the build status
[12:49:27.106] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:27.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:74:0) after 1m0s
[12:50:27.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:74:0)
[12:50:27.103] Timer tick, asking Buildbucket for the build status
[12:50:27.227] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:75:0) after 1m0s
[12:51:27.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:75:0)
[12:51:27.275] Timer tick, asking Buildbucket for the build status
[12:51:27.567] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:76:0) after 1m0s
[12:52:27.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:76:0)
[12:52:27.603] Timer tick, asking Buildbucket for the build status
[12:52:27.724] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:27.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:77:0) after 1m0s
[12:53:27.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:77:0)
[12:53:27.762] Timer tick, asking Buildbucket for the build status
[12:53:27.953] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:27.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:78:0) after 1m0s
[12:54:27.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:78:0)
[12:54:27.951] Timer tick, asking Buildbucket for the build status
[12:54:28.095] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:28.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:79:0) after 1m0s
[12:55:28.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:79:0)
[12:55:28.153] Timer tick, asking Buildbucket for the build status
[12:55:28.240] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:28.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:80:0) after 1m0s
[12:56:28.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:80:0)
[12:56:28.274] Timer tick, asking Buildbucket for the build status
[12:56:28.318] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:28.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:81:0) after 1m0s
[12:57:28.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:81:0)
[12:57:28.331] Timer tick, asking Buildbucket for the build status
[12:57:28.543] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:28.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:82:0) after 1m0s
[12:58:28.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:82:0)
[12:58:28.530] Timer tick, asking Buildbucket for the build status
[12:58:29.877] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:29.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:83:0) after 1m0s
[12:59:29.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:83:0)
[12:59:29.930] Timer tick, asking Buildbucket for the build status
[12:59:30.088] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:30.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:84:0) after 1m0s
[13:00:30.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:84:0)
[13:00:30.126] Timer tick, asking Buildbucket for the build status
[13:00:30.203] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:30.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:85:0) after 1m0s
[13:01:30.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:85:0)
[13:01:30.243] Timer tick, asking Buildbucket for the build status
[13:01:30.483] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:30.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:86:0) after 1m0s
[13:02:30.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:86:0)
[13:02:30.785] Timer tick, asking Buildbucket for the build status
[13:02:30.913] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:87:0) after 1m0s
[13:03:30.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:87:0)
[13:03:30.951] Timer tick, asking Buildbucket for the build status
[13:03:31.003] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:31.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:88:0) after 1m0s
[13:04:31.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:88:0)
[13:04:31.046] Timer tick, asking Buildbucket for the build status
[13:04:31.180] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:31.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:89:0) after 1m0s
[13:05:31.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:89:0)
[13:05:31.218] Timer tick, asking Buildbucket for the build status
[13:05:31.261] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:31.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:90:0) after 1m0s
[13:06:31.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:90:0)
[13:06:31.277] Timer tick, asking Buildbucket for the build status
[13:06:31.389] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:31.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:91:0) after 1m0s
[13:07:31.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:91:0)
[13:07:31.482] Timer tick, asking Buildbucket for the build status
[13:07:31.562] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:31.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:92:0) after 1m0s
[13:08:32.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:92:0)
[13:08:32.087] Timer tick, asking Buildbucket for the build status
[13:08:32.190] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:32.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:93:0) after 1m0s
[13:09:32.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:93:0)
[13:09:32.201] Timer tick, asking Buildbucket for the build status
[13:09:32.301] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:32.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:94:0) after 1m0s
[13:10:32.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:94:0)
[13:10:32.304] Timer tick, asking Buildbucket for the build status
[13:10:32.423] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:32.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:95:0) after 1m0s
[13:11:32.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:95:0)
[13:11:32.420] Timer tick, asking Buildbucket for the build status
[13:11:32.481] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:32.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:96:0) after 1m0s
[13:12:32.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:96:0)
[13:12:32.521] Timer tick, asking Buildbucket for the build status
[13:12:32.592] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:32.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:97:0) after 1m0s
[13:13:32.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:97:0)
[13:13:32.635] Timer tick, asking Buildbucket for the build status
[13:13:33.093] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:33.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:98:0) after 1m0s
[13:14:33.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:98:0)
[13:14:33.116] Timer tick, asking Buildbucket for the build status
[13:14:40.308] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:40.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:99:0) after 1m0s
[13:15:40.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:99:0)
[13:15:40.534] Timer tick, asking Buildbucket for the build status
[13:15:40.692] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:40.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:100:0) after 1m0s
[13:16:40.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:100:0)
[13:16:40.706] Timer tick, asking Buildbucket for the build status
[13:16:40.844] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:40.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:101:0) after 1m0s
[13:17:41.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:101:0)
[13:17:41.000] Timer tick, asking Buildbucket for the build status
[13:17:41.050] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:41.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:102:0) after 1m0s
[13:18:41.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:102:0)
[13:18:41.043] Timer tick, asking Buildbucket for the build status
[13:18:41.570] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:41.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:103:0) after 1m0s
[13:19:41.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:103:0)
[13:19:41.565] Timer tick, asking Buildbucket for the build status
[13:19:41.606] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:41.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:104:0) after 1m0s
[13:20:41.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:104:0)
[13:20:41.627] Timer tick, asking Buildbucket for the build status
[13:20:41.693] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:41.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:105:0) after 1m0s
[13:21:41.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:105:0)
[13:21:41.719] Timer tick, asking Buildbucket for the build status
[13:21:42.058] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:42.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:106:0) after 1m0s
[13:22:42.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:106:0)
[13:22:42.128] Timer tick, asking Buildbucket for the build status
[13:22:42.515] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:42.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:107:0) after 1m0s
[13:23:42.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:107:0)
[13:23:42.548] Timer tick, asking Buildbucket for the build status
[13:23:42.636] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:42.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:108:0) after 1m0s
[13:24:42.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:108:0)
[13:24:42.666] Timer tick, asking Buildbucket for the build status
[13:24:42.751] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:42.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:109:0) after 1m0s
[13:25:42.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:109:0)
[13:25:42.745] Timer tick, asking Buildbucket for the build status
[13:25:42.807] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:42.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:110:0) after 1m0s
[13:26:42.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:110:0)
[13:26:42.856] Timer tick, asking Buildbucket for the build status
[13:26:42.956] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:42.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:111:0) after 1m0s
[13:27:42.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:111:0)
[13:27:42.985] Timer tick, asking Buildbucket for the build status
[13:27:43.068] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:43.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:112:0) after 1m0s
[13:28:43.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:112:0)
[13:28:43.118] Timer tick, asking Buildbucket for the build status
[13:28:43.213] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:43.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:113:0) after 1m0s
[13:29:44.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:113:0)
[13:29:44.473] Timer tick, asking Buildbucket for the build status
[13:29:44.592] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:44.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:114:0) after 1m0s
[13:30:46.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:114:0)
[13:30:46.976] Timer tick, asking Buildbucket for the build status
[13:30:47.099] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:47.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:115:0) after 1m0s
[13:31:47.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:115:0)
[13:31:47.131] Timer tick, asking Buildbucket for the build status
[13:31:47.332] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:47.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:116:0) after 1m0s
[13:32:47.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:116:0)
[13:32:47.368] Timer tick, asking Buildbucket for the build status
[13:32:47.498] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:47.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:117:0) after 1m0s
[13:33:47.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:117:0)
[13:33:47.549] Timer tick, asking Buildbucket for the build status
[13:33:47.623] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:47.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:118:0) after 1m0s
[13:34:47.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:118:0)
[13:34:47.672] Timer tick, asking Buildbucket for the build status
[13:34:48.174] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:48.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:119:0) after 1m0s
[13:35:48.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:119:0)
[13:35:48.304] Timer tick, asking Buildbucket for the build status
[13:35:48.369] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:48.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:120:0) after 1m0s
[13:36:48.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:120:0)
[13:36:48.461] Timer tick, asking Buildbucket for the build status
[13:36:48.718] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:48.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:121:0) after 1m0s
[13:37:48.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:121:0)
[13:37:48.880] Timer tick, asking Buildbucket for the build status
[13:37:49.208] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:49.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:122:0) after 1m0s
[13:38:49.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:122:0)
[13:38:49.225] Timer tick, asking Buildbucket for the build status
[13:38:49.305] Build 8913124103324543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:49.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078554165325469488:123:0) after 1m0s
[13:39:09.995] Received PubSub notification, asking Buildbucket for the build status
[13:39:10.176] Build 8913124103324543728: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:39:10.176] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558186749302673",
"created_by": "project:fuchsia",
"created_ts": "1558179485925971",
"failure_reason": "BUILD_FAILURE",
"id": "8913124103324543728",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4c146ab8c4649d3cfcbc17643f8388cb525bdd56\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-1-wwje\", \"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\": \"4c146ab8c4649d3cfcbc17643f8388cb525bdd56\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-wwje\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4301-0053cb9\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558179522599284",
"status": "COMPLETED",
"status_changed_ts": "1558179522905591",
"tags": [
"builder:clang",
"buildset:commit/git/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4c146ab8c4649d3cfcbc17643f8388cb525bdd56",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078554165325469488",
"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/8913124103324543728/+/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:44e38da791866b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558186749457649",
"url": "https://ci.chromium.org/b/8913124103324543728",
"utcnow_ts": "1558186750164455"
}
}
[13:39:10.176] Invocation finished in 2h1m5.542940925s with status FAILED