[13:00:56.129] New invocation is queued and will start shortly
[13:00:57.218] Starting the invocation (attempt 1)
[13:00:57.267] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:00:57.267] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:00:57.268] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080995070445962624",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"27e9d982eb1e5aaa4c06a8771a3430eb803c1edf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080995070445962624",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"gitiles_ref:refs/heads/master"
]
}
[13:00:58.618] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555851657457221",
"id": "8915565008388657312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"27e9d982eb1e5aaa4c06a8771a3430eb803c1edf\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555851658451737",
"tags": [
"builder:clang",
"buildset:commit/git/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080995070445962624",
"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/8915565008388657312/+/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": "1555851658451901",
"url": "https://ci.chromium.org/b/8915565008388657312",
"utcnow_ts": "1555851658605623"
}
}
[13:00:58.618] Task URL: https://ci.chromium.org/b/8915565008388657312
[13:00:58.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:2:0) after 1m0s
[13:01:09.754] Received PubSub notification, asking Buildbucket for the build status
[13:01:09.822] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:58.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:2:0)
[13:01:58.661] Timer tick, asking Buildbucket for the build status
[13:01:58.790] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:58.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:4:0) after 1m0s
[13:02:58.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:4:0)
[13:02:58.807] Timer tick, asking Buildbucket for the build status
[13:02:58.947] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:58.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:5:0) after 1m0s
[13:03:58.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:5:0)
[13:03:58.971] Timer tick, asking Buildbucket for the build status
[13:03:59.032] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:59.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:6:0) after 1m0s
[13:04:59.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:6:0)
[13:04:59.067] Timer tick, asking Buildbucket for the build status
[13:04:59.158] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:59.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:7:0) after 1m0s
[13:05:59.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:7:0)
[13:05:59.175] Timer tick, asking Buildbucket for the build status
[13:05:59.251] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:59.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:8:0) after 1m0s
[13:06:59.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:8:0)
[13:06:59.271] Timer tick, asking Buildbucket for the build status
[13:06:59.500] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:59.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:9:0) after 1m0s
[13:07:59.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:9:0)
[13:07:59.512] Timer tick, asking Buildbucket for the build status
[13:07:59.607] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:59.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:10:0) after 1m0s
[13:08:59.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:10:0)
[13:08:59.627] Timer tick, asking Buildbucket for the build status
[13:08:59.711] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:59.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:11:0) after 1m0s
[13:09:59.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:11:0)
[13:09:59.728] Timer tick, asking Buildbucket for the build status
[13:09:59.798] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:59.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:12:0) after 1m0s
[13:10:59.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:12:0)
[13:10:59.815] Timer tick, asking Buildbucket for the build status
[13:10:59.983] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:59.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:13:0) after 1m0s
[13:11:59.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:13:0)
[13:11:59.980] Timer tick, asking Buildbucket for the build status
[13:12:00.045] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:00.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:14:0) after 1m0s
[13:13:00.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:14:0)
[13:13:00.068] Timer tick, asking Buildbucket for the build status
[13:13:00.306] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:00.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:15:0) after 1m0s
[13:14:00.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:15:0)
[13:14:00.278] Timer tick, asking Buildbucket for the build status
[13:14:00.525] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:00.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:16:0) after 1m0s
[13:15:00.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:16:0)
[13:15:00.525] Timer tick, asking Buildbucket for the build status
[13:15:00.599] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:00.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:17:0) after 1m0s
[13:16:00.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:17:0)
[13:16:00.619] Timer tick, asking Buildbucket for the build status
[13:16:00.702] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:00.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:18:0) after 1m0s
[13:17:00.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:18:0)
[13:17:00.703] Timer tick, asking Buildbucket for the build status
[13:17:01.008] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:01.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:19:0) after 1m0s
[13:18:01.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:19:0)
[13:18:01.029] Timer tick, asking Buildbucket for the build status
[13:18:01.098] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:01.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:20:0) after 1m0s
[13:19:01.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:20:0)
[13:19:01.096] Timer tick, asking Buildbucket for the build status
[13:19:01.164] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:01.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:21:0) after 1m0s
[13:20:01.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:21:0)
[13:20:01.186] Timer tick, asking Buildbucket for the build status
[13:20:01.278] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:01.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:22:0) after 1m0s
[13:21:01.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:22:0)
[13:21:01.314] Timer tick, asking Buildbucket for the build status
[13:21:01.494] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:01.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:23:0) after 1m0s
[13:22:01.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:23:0)
[13:22:01.524] Timer tick, asking Buildbucket for the build status
[13:22:01.791] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:01.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:24:0) after 1m0s
[13:23:01.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:24:0)
[13:23:01.813] Timer tick, asking Buildbucket for the build status
[13:23:01.871] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:01.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:25:0) after 1m0s
[13:24:01.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:25:0)
[13:24:01.892] Timer tick, asking Buildbucket for the build status
[13:24:01.991] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:01.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:26:0) after 1m0s
[13:25:01.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:26:0)
[13:25:01.976] Timer tick, asking Buildbucket for the build status
[13:25:02.073] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:02.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:27:0) after 1m0s
[13:26:02.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:27:0)
[13:26:02.095] Timer tick, asking Buildbucket for the build status
[13:26:02.255] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:02.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:28:0) after 1m0s
[13:27:02.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:28:0)
[13:27:02.274] Timer tick, asking Buildbucket for the build status
[13:27:02.338] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:02.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:29:0) after 1m0s
[13:28:02.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:29:0)
[13:28:02.383] Timer tick, asking Buildbucket for the build status
[13:28:02.587] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:02.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:30:0) after 1m0s
[13:29:02.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:30:0)
[13:29:02.605] Timer tick, asking Buildbucket for the build status
[13:29:02.684] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:02.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:31:0) after 1m0s
[13:30:02.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:31:0)
[13:30:02.715] Timer tick, asking Buildbucket for the build status
[13:30:02.860] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:02.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:32:0) after 1m0s
[13:31:02.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:32:0)
[13:31:02.883] Timer tick, asking Buildbucket for the build status
[13:31:02.970] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:02.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:33:0) after 1m0s
[13:32:02.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:33:0)
[13:32:02.966] Timer tick, asking Buildbucket for the build status
[13:32:03.028] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:03.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:34:0) after 1m0s
[13:33:03.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:34:0)
[13:33:03.067] Timer tick, asking Buildbucket for the build status
[13:33:03.197] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:03.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:35:0) after 1m0s
[13:34:03.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:35:0)
[13:34:03.296] Timer tick, asking Buildbucket for the build status
[13:34:03.440] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:03.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:36:0) after 1m0s
[13:35:03.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:36:0)
[13:35:03.462] Timer tick, asking Buildbucket for the build status
[13:35:03.860] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:03.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:37:0) after 1m0s
[13:36:03.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:37:0)
[13:36:03.879] Timer tick, asking Buildbucket for the build status
[13:36:04.105] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:04.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:38:0) after 1m0s
[13:37:04.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:38:0)
[13:37:04.087] Timer tick, asking Buildbucket for the build status
[13:37:04.185] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:04.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:39:0) after 1m0s
[13:38:04.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:39:0)
[13:38:04.193] Timer tick, asking Buildbucket for the build status
[13:38:04.317] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:04.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:40:0) after 1m0s
[13:39:04.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:40:0)
[13:39:04.339] Timer tick, asking Buildbucket for the build status
[13:39:04.423] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:04.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:41:0) after 1m0s
[13:40:04.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:41:0)
[13:40:04.450] Timer tick, asking Buildbucket for the build status
[13:40:04.521] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:04.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:42:0) after 1m0s
[13:41:04.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:42:0)
[13:41:04.563] Timer tick, asking Buildbucket for the build status
[13:41:04.645] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:04.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:43:0) after 1m0s
[13:42:04.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:43:0)
[13:42:04.617] Timer tick, asking Buildbucket for the build status
[13:42:04.702] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:04.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:44:0) after 1m0s
[13:43:04.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:44:0)
[13:43:04.726] Timer tick, asking Buildbucket for the build status
[13:43:04.785] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:04.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:45:0) after 1m0s
[13:44:04.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:45:0)
[13:44:04.801] Timer tick, asking Buildbucket for the build status
[13:44:05.180] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:05.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:46:0) after 1m0s
[13:45:05.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:46:0)
[13:45:05.198] Timer tick, asking Buildbucket for the build status
[13:45:05.282] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:05.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:47:0) after 1m0s
[13:46:05.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:47:0)
[13:46:05.301] Timer tick, asking Buildbucket for the build status
[13:46:05.375] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:05.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:48:0) after 1m0s
[13:47:05.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:48:0)
[13:47:05.395] Timer tick, asking Buildbucket for the build status
[13:47:05.461] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:05.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:49:0) after 1m0s
[13:48:05.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:49:0)
[13:48:05.485] Timer tick, asking Buildbucket for the build status
[13:48:05.589] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:05.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:50:0) after 1m0s
[13:49:05.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:50:0)
[13:49:05.608] Timer tick, asking Buildbucket for the build status
[13:49:05.678] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:05.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:51:0) after 1m0s
[13:50:05.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:51:0)
[13:50:05.698] Timer tick, asking Buildbucket for the build status
[13:50:05.972] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:05.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:52:0) after 1m0s
[13:51:05.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:52:0)
[13:51:05.998] Timer tick, asking Buildbucket for the build status
[13:51:06.286] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:06.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:53:0) after 1m0s
[13:52:06.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:53:0)
[13:52:06.306] Timer tick, asking Buildbucket for the build status
[13:52:06.404] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:06.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:54:0) after 1m0s
[13:53:06.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:54:0)
[13:53:06.426] Timer tick, asking Buildbucket for the build status
[13:53:06.526] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:06.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:55:0) after 1m0s
[13:54:06.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:55:0)
[13:54:06.551] Timer tick, asking Buildbucket for the build status
[13:54:07.009] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:07.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:56:0) after 1m0s
[13:55:07.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:56:0)
[13:55:07.028] Timer tick, asking Buildbucket for the build status
[13:55:07.366] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:07.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:57:0) after 1m0s
[13:56:07.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:57:0)
[13:56:07.385] Timer tick, asking Buildbucket for the build status
[13:56:07.558] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:07.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:58:0) after 1m0s
[13:57:07.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:58:0)
[13:57:07.577] Timer tick, asking Buildbucket for the build status
[13:57:07.706] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:07.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:59:0) after 1m0s
[13:58:07.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:59:0)
[13:58:07.725] Timer tick, asking Buildbucket for the build status
[13:58:07.797] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:07.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:60:0) after 1m0s
[13:59:07.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:60:0)
[13:59:07.803] Timer tick, asking Buildbucket for the build status
[13:59:07.977] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:07.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:61:0) after 1m0s
[14:00:07.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:61:0)
[14:00:07.999] Timer tick, asking Buildbucket for the build status
[14:00:08.081] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:08.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:62:0) after 1m0s
[14:01:08.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:62:0)
[14:01:08.103] Timer tick, asking Buildbucket for the build status
[14:01:08.227] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:08.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:63:0) after 1m0s
[14:02:08.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:63:0)
[14:02:08.207] Timer tick, asking Buildbucket for the build status
[14:02:08.270] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:08.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:64:0) after 1m0s
[14:03:08.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:64:0)
[14:03:08.336] Timer tick, asking Buildbucket for the build status
[14:03:08.423] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:08.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:65:0) after 1m0s
[14:04:08.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:65:0)
[14:04:08.443] Timer tick, asking Buildbucket for the build status
[14:04:08.524] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:08.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:66:0) after 1m0s
[14:05:08.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:66:0)
[14:05:08.545] Timer tick, asking Buildbucket for the build status
[14:05:08.652] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:08.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:67:0) after 1m0s
[14:06:08.681] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:67:0)
[14:06:08.681] Timer tick, asking Buildbucket for the build status
[14:06:08.803] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:08.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:68:0) after 1m0s
[14:07:08.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:68:0)
[14:07:08.797] Timer tick, asking Buildbucket for the build status
[14:07:08.857] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:08.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:69:0) after 1m0s
[14:08:08.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:69:0)
[14:08:08.916] Timer tick, asking Buildbucket for the build status
[14:08:08.973] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:08.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:70:0) after 1m0s
[14:09:08.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:70:0)
[14:09:08.970] Timer tick, asking Buildbucket for the build status
[14:09:09.039] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:09.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:71:0) after 1m0s
[14:10:09.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:71:0)
[14:10:09.098] Timer tick, asking Buildbucket for the build status
[14:10:09.173] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:09.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:72:0) after 1m0s
[14:11:09.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:72:0)
[14:11:09.196] Timer tick, asking Buildbucket for the build status
[14:11:09.254] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:09.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:73:0) after 1m0s
[14:12:09.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:73:0)
[14:12:09.234] Timer tick, asking Buildbucket for the build status
[14:12:09.488] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:09.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:74:0) after 1m0s
[14:13:09.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:74:0)
[14:13:09.472] Timer tick, asking Buildbucket for the build status
[14:13:09.586] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:09.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:75:0) after 1m0s
[14:14:09.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:75:0)
[14:14:09.609] Timer tick, asking Buildbucket for the build status
[14:14:09.674] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:09.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:76:0) after 1m0s
[14:15:09.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:76:0)
[14:15:09.693] Timer tick, asking Buildbucket for the build status
[14:15:09.803] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:09.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:77:0) after 1m0s
[14:16:09.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:77:0)
[14:16:09.797] Timer tick, asking Buildbucket for the build status
[14:16:09.900] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:09.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:78:0) after 1m0s
[14:17:09.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:78:0)
[14:17:09.909] Timer tick, asking Buildbucket for the build status
[14:17:10.078] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:10.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:79:0) after 1m0s
[14:18:10.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:79:0)
[14:18:10.085] Timer tick, asking Buildbucket for the build status
[14:18:10.262] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:10.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:80:0) after 1m0s
[14:19:10.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:80:0)
[14:19:10.289] Timer tick, asking Buildbucket for the build status
[14:19:10.351] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:10.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:81:0) after 1m0s
[14:20:10.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:81:0)
[14:20:10.362] Timer tick, asking Buildbucket for the build status
[14:20:10.401] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:10.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:82:0) after 1m0s
[14:21:10.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:82:0)
[14:21:10.424] Timer tick, asking Buildbucket for the build status
[14:21:10.480] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:10.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:83:0) after 1m0s
[14:22:10.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:83:0)
[14:22:10.494] Timer tick, asking Buildbucket for the build status
[14:22:10.560] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:10.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:84:0) after 1m0s
[14:23:10.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:84:0)
[14:23:10.543] Timer tick, asking Buildbucket for the build status
[14:23:10.682] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:10.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:85:0) after 1m0s
[14:24:10.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:85:0)
[14:24:10.688] Timer tick, asking Buildbucket for the build status
[14:24:10.765] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:10.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:86:0) after 1m0s
[14:25:10.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:86:0)
[14:25:10.764] Timer tick, asking Buildbucket for the build status
[14:25:10.835] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:10.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:87:0) after 1m0s
[14:26:10.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:87:0)
[14:26:10.855] Timer tick, asking Buildbucket for the build status
[14:26:10.991] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:10.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:88:0) after 1m0s
[14:27:10.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:88:0)
[14:27:10.995] Timer tick, asking Buildbucket for the build status
[14:27:11.068] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:11.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:89:0) after 1m0s
[14:28:11.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:89:0)
[14:28:11.091] Timer tick, asking Buildbucket for the build status
[14:28:11.166] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:11.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:90:0) after 1m0s
[14:29:11.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:90:0)
[14:29:11.187] Timer tick, asking Buildbucket for the build status
[14:29:11.254] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:11.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:91:0) after 1m0s
[14:30:11.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:91:0)
[14:30:11.243] Timer tick, asking Buildbucket for the build status
[14:30:11.358] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:11.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:92:0) after 1m0s
[14:31:11.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:92:0)
[14:31:11.382] Timer tick, asking Buildbucket for the build status
[14:31:11.450] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:11.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:93:0) after 1m0s
[14:32:11.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:93:0)
[14:32:11.478] Timer tick, asking Buildbucket for the build status
[14:32:11.542] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:11.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:94:0) after 1m0s
[14:33:11.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:94:0)
[14:33:11.532] Timer tick, asking Buildbucket for the build status
[14:33:11.590] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:11.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:95:0) after 1m0s
[14:34:11.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:95:0)
[14:34:11.610] Timer tick, asking Buildbucket for the build status
[14:34:11.864] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:11.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:96:0) after 1m0s
[14:35:11.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:96:0)
[14:35:11.900] Timer tick, asking Buildbucket for the build status
[14:35:11.968] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:11.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:97:0) after 1m0s
[14:36:12.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:97:0)
[14:36:12.006] Timer tick, asking Buildbucket for the build status
[14:36:12.539] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:12.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:98:0) after 1m0s
[14:37:12.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:98:0)
[14:37:12.587] Timer tick, asking Buildbucket for the build status
[14:37:12.639] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:12.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:99:0) after 1m0s
[14:38:12.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:99:0)
[14:38:12.671] Timer tick, asking Buildbucket for the build status
[14:38:12.808] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:12.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:100:0) after 1m0s
[14:39:12.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:100:0)
[14:39:12.830] Timer tick, asking Buildbucket for the build status
[14:39:12.891] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:12.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:101:0) after 1m0s
[14:40:12.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:101:0)
[14:40:12.912] Timer tick, asking Buildbucket for the build status
[14:40:12.993] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:12.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:102:0) after 1m0s
[14:41:12.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:102:0)
[14:41:12.995] Timer tick, asking Buildbucket for the build status
[14:41:13.092] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:13.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:103:0) after 1m0s
[14:42:13.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:103:0)
[14:42:13.118] Timer tick, asking Buildbucket for the build status
[14:42:13.307] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:13.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:104:0) after 1m0s
[14:43:13.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:104:0)
[14:43:13.326] Timer tick, asking Buildbucket for the build status
[14:43:13.382] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:13.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:105:0) after 1m0s
[14:44:13.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:105:0)
[14:44:13.407] Timer tick, asking Buildbucket for the build status
[14:44:13.595] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:13.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:106:0) after 1m0s
[14:45:13.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:106:0)
[14:45:13.616] Timer tick, asking Buildbucket for the build status
[14:45:13.681] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:13.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:107:0) after 1m0s
[14:46:13.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:107:0)
[14:46:13.702] Timer tick, asking Buildbucket for the build status
[14:46:13.981] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:13.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:108:0) after 1m0s
[14:47:13.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:108:0)
[14:47:13.967] Timer tick, asking Buildbucket for the build status
[14:47:14.296] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:14.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:109:0) after 1m0s
[14:48:14.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:109:0)
[14:48:14.317] Timer tick, asking Buildbucket for the build status
[14:48:14.441] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:14.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:110:0) after 1m0s
[14:49:14.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:110:0)
[14:49:14.453] Timer tick, asking Buildbucket for the build status
[14:49:14.589] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:14.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:111:0) after 1m0s
[14:50:14.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:111:0)
[14:50:14.587] Timer tick, asking Buildbucket for the build status
[14:50:14.639] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:14.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:112:0) after 1m0s
[14:51:14.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:112:0)
[14:51:14.654] Timer tick, asking Buildbucket for the build status
[14:51:14.710] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:14.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:113:0) after 1m0s
[14:52:14.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:113:0)
[14:52:14.707] Timer tick, asking Buildbucket for the build status
[14:52:14.813] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:14.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:114:0) after 1m0s
[14:53:14.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:114:0)
[14:53:14.796] Timer tick, asking Buildbucket for the build status
[14:53:14.971] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:14.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:115:0) after 1m0s
[14:54:14.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:115:0)
[14:54:14.989] Timer tick, asking Buildbucket for the build status
[14:54:15.256] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:15.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:116:0) after 1m0s
[14:55:15.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:116:0)
[14:55:15.274] Timer tick, asking Buildbucket for the build status
[14:55:15.332] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:15.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:117:0) after 1m0s
[14:56:15.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:117:0)
[14:56:15.305] Timer tick, asking Buildbucket for the build status
[14:56:15.370] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:15.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:118:0) after 1m0s
[14:57:15.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:118:0)
[14:57:15.392] Timer tick, asking Buildbucket for the build status
[14:57:15.464] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:15.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:119:0) after 1m0s
[14:58:15.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:119:0)
[14:58:15.487] Timer tick, asking Buildbucket for the build status
[14:58:15.530] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:15.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:120:0) after 1m0s
[14:59:15.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:120:0)
[14:59:15.514] Timer tick, asking Buildbucket for the build status
[14:59:15.578] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:15.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:121:0) after 1m0s
[15:00:15.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:121:0)
[15:00:15.601] Timer tick, asking Buildbucket for the build status
[15:00:15.666] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:15.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:122:0) after 1m0s
[15:01:15.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:122:0)
[15:01:15.654] Timer tick, asking Buildbucket for the build status
[15:01:15.864] Build 8915565008388657312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:15.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080995070445962624:123:0) after 1m0s
[15:01:30.229] Received PubSub notification, asking Buildbucket for the build status
[15:01:30.381] Build 8915565008388657312: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:01:30.381] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555858889941843",
"created_by": "project:fuchsia",
"created_ts": "1555851657457221",
"failure_reason": "BUILD_FAILURE",
"id": "8915565008388657312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"27e9d982eb1e5aaa4c06a8771a3430eb803c1edf\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-c-1hgq\", \"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\": \"27e9d982eb1e5aaa4c06a8771a3430eb803c1edf\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-1hgq\"], \"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\": [\"4267-53b17d5\"], \"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": "1555851668700656",
"status": "COMPLETED",
"status_changed_ts": "1555851669391173",
"tags": [
"builder:clang",
"buildset:commit/git/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/27e9d982eb1e5aaa4c06a8771a3430eb803c1edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080995070445962624",
"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/8915565008388657312/+/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:4458cdce14553010",
"user_agent:luci-scheduler"
],
"updated_ts": "1555858889987368",
"url": "https://ci.chromium.org/b/8915565008388657312",
"utcnow_ts": "1555858890369010"
}
}
[15:01:30.381] Invocation finished in 2h0m34.269584831s with status FAILED