[00:58:30.081] New invocation is queued and will start shortly
[00:58:31.171] Starting the invocation (attempt 1)
[00:58:31.209] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:58:31.209] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:58:31.209] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083486640162913600",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b15c35aff878cd59328de80b95ba35ab0399b2c8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083486640162913600",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"gitiles_ref:refs/heads/master"
]
}
[00:58:32.241] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553475511289300",
"id": "8918056578233366400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b15c35aff878cd59328de80b95ba35ab0399b2c8\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553475512145136",
"tags": [
"builder:clang",
"buildset:commit/git/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083486640162913600",
"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/8918056578233366400/+/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:43cb2cad70b65310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553475512145251",
"url": "https://ci.chromium.org/b/8918056578233366400",
"utcnow_ts": "1553475512229996"
}
}
[00:58:32.241] Task URL: https://ci.chromium.org/b/8918056578233366400
[00:58:32.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:2:0) after 1m0s
[00:58:46.624] Received PubSub notification, asking Buildbucket for the build status
[00:58:46.670] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:32.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:2:0)
[00:59:32.257] Timer tick, asking Buildbucket for the build status
[00:59:32.378] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:32.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:4:0) after 1m0s
[01:00:32.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:4:0)
[01:00:32.398] Timer tick, asking Buildbucket for the build status
[01:00:32.501] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:32.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:5:0) after 1m0s
[01:01:32.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:5:0)
[01:01:32.529] Timer tick, asking Buildbucket for the build status
[01:01:32.720] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:32.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:6:0) after 1m0s
[01:02:32.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:6:0)
[01:02:32.709] Timer tick, asking Buildbucket for the build status
[01:02:32.768] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:32.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:7:0) after 1m0s
[01:03:32.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:7:0)
[01:03:32.775] Timer tick, asking Buildbucket for the build status
[01:03:32.837] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:32.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:8:0) after 1m0s
[01:04:32.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:8:0)
[01:04:32.856] Timer tick, asking Buildbucket for the build status
[01:04:33.005] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:33.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:9:0) after 1m0s
[01:05:33.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:9:0)
[01:05:33.074] Timer tick, asking Buildbucket for the build status
[01:05:33.163] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:33.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:10:0) after 1m0s
[01:06:33.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:10:0)
[01:06:33.183] Timer tick, asking Buildbucket for the build status
[01:06:33.223] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:33.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:11:0) after 1m0s
[01:07:33.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:11:0)
[01:07:33.245] Timer tick, asking Buildbucket for the build status
[01:07:33.490] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:33.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:12:0) after 1m0s
[01:08:33.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:12:0)
[01:08:33.485] Timer tick, asking Buildbucket for the build status
[01:08:33.540] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:33.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:13:0) after 1m0s
[01:09:33.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:13:0)
[01:09:33.557] Timer tick, asking Buildbucket for the build status
[01:09:33.796] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:33.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:14:0) after 1m0s
[01:10:33.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:14:0)
[01:10:33.813] Timer tick, asking Buildbucket for the build status
[01:10:33.900] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:33.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:15:0) after 1m0s
[01:11:33.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:15:0)
[01:11:33.893] Timer tick, asking Buildbucket for the build status
[01:11:33.952] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:16:0) after 1m0s
[01:12:33.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:16:0)
[01:12:33.946] Timer tick, asking Buildbucket for the build status
[01:12:34.019] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:34.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:17:0) after 1m0s
[01:13:34.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:17:0)
[01:13:34.049] Timer tick, asking Buildbucket for the build status
[01:13:34.214] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:34.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:18:0) after 1m0s
[01:14:34.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:18:0)
[01:14:34.242] Timer tick, asking Buildbucket for the build status
[01:14:34.364] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:34.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:19:0) after 1m0s
[01:15:34.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:19:0)
[01:15:34.384] Timer tick, asking Buildbucket for the build status
[01:15:34.510] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:34.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:20:0) after 1m0s
[01:16:34.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:20:0)
[01:16:34.523] Timer tick, asking Buildbucket for the build status
[01:16:34.611] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:34.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:21:0) after 1m0s
[01:17:34.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:21:0)
[01:17:34.629] Timer tick, asking Buildbucket for the build status
[01:17:34.790] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:34.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:22:0) after 1m0s
[01:18:34.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:22:0)
[01:18:34.809] Timer tick, asking Buildbucket for the build status
[01:18:34.909] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:34.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:23:0) after 1m0s
[01:19:34.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:23:0)
[01:19:34.928] Timer tick, asking Buildbucket for the build status
[01:19:35.003] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:35.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:24:0) after 1m0s
[01:20:35.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:24:0)
[01:20:35.021] Timer tick, asking Buildbucket for the build status
[01:20:35.285] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:35.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:25:0) after 1m0s
[01:21:35.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:25:0)
[01:21:35.303] Timer tick, asking Buildbucket for the build status
[01:21:35.383] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:35.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:26:0) after 1m0s
[01:22:35.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:26:0)
[01:22:35.371] Timer tick, asking Buildbucket for the build status
[01:22:35.446] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:35.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:27:0) after 1m0s
[01:23:35.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:27:0)
[01:23:35.449] Timer tick, asking Buildbucket for the build status
[01:23:35.534] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:35.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:28:0) after 1m0s
[01:24:35.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:28:0)
[01:24:35.546] Timer tick, asking Buildbucket for the build status
[01:24:35.700] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:35.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:29:0) after 1m0s
[01:25:35.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:29:0)
[01:25:35.716] Timer tick, asking Buildbucket for the build status
[01:25:35.794] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:35.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:30:0) after 1m0s
[01:26:35.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:30:0)
[01:26:35.790] Timer tick, asking Buildbucket for the build status
[01:26:35.975] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:35.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:31:0) after 1m0s
[01:27:35.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:31:0)
[01:27:35.969] Timer tick, asking Buildbucket for the build status
[01:27:36.094] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:36.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:32:0) after 1m0s
[01:28:36.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:32:0)
[01:28:36.123] Timer tick, asking Buildbucket for the build status
[01:28:36.186] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:36.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:33:0) after 1m0s
[01:29:36.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:33:0)
[01:29:36.215] Timer tick, asking Buildbucket for the build status
[01:29:36.290] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:36.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:34:0) after 1m0s
[01:30:36.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:34:0)
[01:30:36.295] Timer tick, asking Buildbucket for the build status
[01:30:36.375] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:36.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:35:0) after 1m0s
[01:31:36.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:35:0)
[01:31:36.375] Timer tick, asking Buildbucket for the build status
[01:31:36.436] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:36.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:36:0) after 1m0s
[01:32:36.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:36:0)
[01:32:36.457] Timer tick, asking Buildbucket for the build status
[01:32:36.496] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:36.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:37:0) after 1m0s
[01:33:36.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:37:0)
[01:33:36.483] Timer tick, asking Buildbucket for the build status
[01:33:36.546] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:36.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:38:0) after 1m0s
[01:34:36.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:38:0)
[01:34:36.547] Timer tick, asking Buildbucket for the build status
[01:34:36.616] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:36.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:39:0) after 1m0s
[01:35:36.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:39:0)
[01:35:36.638] Timer tick, asking Buildbucket for the build status
[01:35:36.706] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:36.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:40:0) after 1m0s
[01:36:36.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:40:0)
[01:36:36.742] Timer tick, asking Buildbucket for the build status
[01:36:36.823] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:36.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:41:0) after 1m0s
[01:37:36.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:41:0)
[01:37:36.842] Timer tick, asking Buildbucket for the build status
[01:37:36.929] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:36.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:42:0) after 1m0s
[01:38:36.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:42:0)
[01:38:36.971] Timer tick, asking Buildbucket for the build status
[01:38:37.033] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:37.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:43:0) after 1m0s
[01:39:37.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:43:0)
[01:39:37.052] Timer tick, asking Buildbucket for the build status
[01:39:37.375] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:37.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:44:0) after 1m0s
[01:40:37.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:44:0)
[01:40:37.394] Timer tick, asking Buildbucket for the build status
[01:40:37.474] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:37.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:45:0) after 1m0s
[01:41:37.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:45:0)
[01:41:37.454] Timer tick, asking Buildbucket for the build status
[01:41:37.513] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:37.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:46:0) after 1m0s
[01:42:37.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:46:0)
[01:42:37.533] Timer tick, asking Buildbucket for the build status
[01:42:37.595] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:37.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:47:0) after 1m0s
[01:43:37.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:47:0)
[01:43:37.615] Timer tick, asking Buildbucket for the build status
[01:43:37.687] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:37.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:48:0) after 1m0s
[01:44:37.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:48:0)
[01:44:37.699] Timer tick, asking Buildbucket for the build status
[01:44:37.779] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:37.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:49:0) after 1m0s
[01:45:37.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:49:0)
[01:45:37.782] Timer tick, asking Buildbucket for the build status
[01:45:37.869] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:37.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:50:0) after 1m0s
[01:46:37.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:50:0)
[01:46:37.892] Timer tick, asking Buildbucket for the build status
[01:46:37.971] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:37.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:51:0) after 1m0s
[01:47:37.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:51:0)
[01:47:37.984] Timer tick, asking Buildbucket for the build status
[01:47:38.119] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:38.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:52:0) after 1m0s
[01:48:38.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:52:0)
[01:48:38.098] Timer tick, asking Buildbucket for the build status
[01:48:38.186] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:38.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:53:0) after 1m0s
[01:49:38.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:53:0)
[01:49:38.186] Timer tick, asking Buildbucket for the build status
[01:49:38.247] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:38.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:54:0) after 1m0s
[01:50:38.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:54:0)
[01:50:38.266] Timer tick, asking Buildbucket for the build status
[01:50:38.348] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:38.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:55:0) after 1m0s
[01:51:38.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:55:0)
[01:51:38.356] Timer tick, asking Buildbucket for the build status
[01:51:38.598] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:38.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:56:0) after 1m0s
[01:52:38.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:56:0)
[01:52:38.633] Timer tick, asking Buildbucket for the build status
[01:52:38.694] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:38.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:57:0) after 1m0s
[01:53:38.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:57:0)
[01:53:38.713] Timer tick, asking Buildbucket for the build status
[01:53:38.795] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:38.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:58:0) after 1m0s
[01:54:38.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:58:0)
[01:54:38.816] Timer tick, asking Buildbucket for the build status
[01:54:38.911] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:38.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:59:0) after 1m0s
[01:55:38.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:59:0)
[01:55:38.918] Timer tick, asking Buildbucket for the build status
[01:55:38.994] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:38.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:60:0) after 1m0s
[01:56:39.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:60:0)
[01:56:39.014] Timer tick, asking Buildbucket for the build status
[01:56:39.117] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:39.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:61:0) after 1m0s
[01:57:39.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:61:0)
[01:57:39.131] Timer tick, asking Buildbucket for the build status
[01:57:39.227] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:39.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:62:0) after 1m0s
[01:58:39.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:62:0)
[01:58:39.252] Timer tick, asking Buildbucket for the build status
[01:58:39.316] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:39.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:63:0) after 1m0s
[01:59:39.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:63:0)
[01:59:39.299] Timer tick, asking Buildbucket for the build status
[01:59:39.475] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:39.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:64:0) after 1m0s
[02:00:39.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:64:0)
[02:00:39.463] Timer tick, asking Buildbucket for the build status
[02:00:39.559] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:39.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:65:0) after 1m0s
[02:01:39.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:65:0)
[02:01:39.535] Timer tick, asking Buildbucket for the build status
[02:01:39.642] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:39.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:66:0) after 1m0s
[02:02:39.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:66:0)
[02:02:39.676] Timer tick, asking Buildbucket for the build status
[02:02:39.742] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:39.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:67:0) after 1m0s
[02:03:39.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:67:0)
[02:03:39.776] Timer tick, asking Buildbucket for the build status
[02:03:39.838] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:39.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:68:0) after 1m0s
[02:04:39.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:68:0)
[02:04:39.867] Timer tick, asking Buildbucket for the build status
[02:04:39.920] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:39.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:69:0) after 1m0s
[02:05:39.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:69:0)
[02:05:39.938] Timer tick, asking Buildbucket for the build status
[02:05:40.014] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:40.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:70:0) after 1m0s
[02:06:40.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:70:0)
[02:06:40.027] Timer tick, asking Buildbucket for the build status
[02:06:40.100] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:40.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:71:0) after 1m0s
[02:07:40.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:71:0)
[02:07:40.121] Timer tick, asking Buildbucket for the build status
[02:07:40.217] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:40.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:72:0) after 1m0s
[02:08:40.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:72:0)
[02:08:40.239] Timer tick, asking Buildbucket for the build status
[02:08:40.314] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:40.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:73:0) after 1m0s
[02:09:40.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:73:0)
[02:09:40.323] Timer tick, asking Buildbucket for the build status
[02:09:40.391] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:40.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:74:0) after 1m0s
[02:10:40.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:74:0)
[02:10:40.413] Timer tick, asking Buildbucket for the build status
[02:10:40.481] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:40.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:75:0) after 1m0s
[02:11:40.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:75:0)
[02:11:40.464] Timer tick, asking Buildbucket for the build status
[02:11:40.666] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:40.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:76:0) after 1m0s
[02:12:40.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:76:0)
[02:12:40.640] Timer tick, asking Buildbucket for the build status
[02:12:40.763] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:40.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:77:0) after 1m0s
[02:13:40.746] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:77:0)
[02:13:40.746] Timer tick, asking Buildbucket for the build status
[02:13:40.809] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:40.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:78:0) after 1m0s
[02:14:40.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:78:0)
[02:14:40.801] Timer tick, asking Buildbucket for the build status
[02:14:40.952] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:40.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:79:0) after 1m0s
[02:15:40.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:79:0)
[02:15:40.942] Timer tick, asking Buildbucket for the build status
[02:15:41.001] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:41.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:80:0) after 1m0s
[02:16:41.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:80:0)
[02:16:41.013] Timer tick, asking Buildbucket for the build status
[02:16:41.079] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:41.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:81:0) after 1m0s
[02:17:41.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:81:0)
[02:17:41.095] Timer tick, asking Buildbucket for the build status
[02:17:41.177] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:41.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:82:0) after 1m0s
[02:18:41.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:82:0)
[02:18:41.152] Timer tick, asking Buildbucket for the build status
[02:18:41.274] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:41.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:83:0) after 1m0s
[02:19:41.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:83:0)
[02:19:41.308] Timer tick, asking Buildbucket for the build status
[02:19:41.367] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:41.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:84:0) after 1m0s
[02:20:41.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:84:0)
[02:20:41.401] Timer tick, asking Buildbucket for the build status
[02:20:41.517] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:41.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:85:0) after 1m0s
[02:21:41.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:85:0)
[02:21:41.534] Timer tick, asking Buildbucket for the build status
[02:21:41.595] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:41.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:86:0) after 1m0s
[02:22:41.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:86:0)
[02:22:41.652] Timer tick, asking Buildbucket for the build status
[02:22:41.770] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:41.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:87:0) after 1m0s
[02:23:41.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:87:0)
[02:23:41.761] Timer tick, asking Buildbucket for the build status
[02:23:41.834] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:41.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:88:0) after 1m0s
[02:24:41.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:88:0)
[02:24:41.860] Timer tick, asking Buildbucket for the build status
[02:24:42.015] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:42.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:89:0) after 1m0s
[02:25:42.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:89:0)
[02:25:42.036] Timer tick, asking Buildbucket for the build status
[02:25:42.109] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:42.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:90:0) after 1m0s
[02:26:42.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:90:0)
[02:26:42.159] Timer tick, asking Buildbucket for the build status
[02:26:42.219] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:42.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:91:0) after 1m0s
[02:27:42.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:91:0)
[02:27:42.249] Timer tick, asking Buildbucket for the build status
[02:27:43.731] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:43.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:92:0) after 1m0s
[02:28:43.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:92:0)
[02:28:43.731] Timer tick, asking Buildbucket for the build status
[02:28:43.780] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:43.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:93:0) after 1m0s
[02:29:43.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:93:0)
[02:29:43.800] Timer tick, asking Buildbucket for the build status
[02:29:43.863] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:43.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:94:0) after 1m0s
[02:30:43.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:94:0)
[02:30:43.867] Timer tick, asking Buildbucket for the build status
[02:30:44.079] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:44.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:95:0) after 1m0s
[02:31:44.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:95:0)
[02:31:44.104] Timer tick, asking Buildbucket for the build status
[02:31:44.177] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:44.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:96:0) after 1m0s
[02:32:44.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:96:0)
[02:32:44.206] Timer tick, asking Buildbucket for the build status
[02:32:44.267] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:97:0) after 1m0s
[02:33:44.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:97:0)
[02:33:44.253] Timer tick, asking Buildbucket for the build status
[02:33:44.373] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:44.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:98:0) after 1m0s
[02:34:44.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:98:0)
[02:34:44.406] Timer tick, asking Buildbucket for the build status
[02:34:44.619] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:44.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:99:0) after 1m0s
[02:35:44.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:99:0)
[02:35:44.640] Timer tick, asking Buildbucket for the build status
[02:35:44.741] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:44.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:100:0) after 1m0s
[02:36:44.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:100:0)
[02:36:44.764] Timer tick, asking Buildbucket for the build status
[02:36:44.897] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:44.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:101:0) after 1m0s
[02:37:44.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:101:0)
[02:37:44.918] Timer tick, asking Buildbucket for the build status
[02:37:44.975] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:44.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:102:0) after 1m0s
[02:38:44.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:102:0)
[02:38:44.997] Timer tick, asking Buildbucket for the build status
[02:38:45.194] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:45.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:103:0) after 1m0s
[02:39:45.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:103:0)
[02:39:45.267] Timer tick, asking Buildbucket for the build status
[02:39:45.390] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:45.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:104:0) after 1m0s
[02:40:45.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:104:0)
[02:40:45.396] Timer tick, asking Buildbucket for the build status
[02:40:45.646] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:45.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:105:0) after 1m0s
[02:41:45.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:105:0)
[02:41:45.667] Timer tick, asking Buildbucket for the build status
[02:41:45.738] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:45.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:106:0) after 1m0s
[02:42:45.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:106:0)
[02:42:45.778] Timer tick, asking Buildbucket for the build status
[02:42:45.954] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:45.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:107:0) after 1m0s
[02:43:45.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:107:0)
[02:43:45.970] Timer tick, asking Buildbucket for the build status
[02:43:46.029] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:46.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:108:0) after 1m0s
[02:44:46.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:108:0)
[02:44:46.045] Timer tick, asking Buildbucket for the build status
[02:44:46.144] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:46.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:109:0) after 1m0s
[02:45:46.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:109:0)
[02:45:46.161] Timer tick, asking Buildbucket for the build status
[02:45:46.510] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:46.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:110:0) after 1m0s
[02:46:46.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:110:0)
[02:46:46.523] Timer tick, asking Buildbucket for the build status
[02:46:46.628] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:46.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:111:0) after 1m0s
[02:47:46.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:111:0)
[02:47:46.635] Timer tick, asking Buildbucket for the build status
[02:47:46.702] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:46.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:112:0) after 1m0s
[02:48:46.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:112:0)
[02:48:46.722] Timer tick, asking Buildbucket for the build status
[02:48:46.785] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:46.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:113:0) after 1m0s
[02:49:46.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:113:0)
[02:49:46.791] Timer tick, asking Buildbucket for the build status
[02:49:46.855] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:46.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:114:0) after 1m0s
[02:50:46.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:114:0)
[02:50:46.887] Timer tick, asking Buildbucket for the build status
[02:50:46.987] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:46.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:115:0) after 1m0s
[02:51:46.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:115:0)
[02:51:46.987] Timer tick, asking Buildbucket for the build status
[02:51:47.118] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:47.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:116:0) after 1m0s
[02:52:47.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:116:0)
[02:52:47.109] Timer tick, asking Buildbucket for the build status
[02:52:47.169] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:117:0) after 1m0s
[02:53:47.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:117:0)
[02:53:47.204] Timer tick, asking Buildbucket for the build status
[02:53:47.434] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:47.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:118:0) after 1m0s
[02:54:47.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:118:0)
[02:54:47.460] Timer tick, asking Buildbucket for the build status
[02:54:47.527] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:47.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:119:0) after 1m0s
[02:55:47.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:119:0)
[02:55:47.550] Timer tick, asking Buildbucket for the build status
[02:55:47.614] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:47.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:120:0) after 1m0s
[02:56:47.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:120:0)
[02:56:47.627] Timer tick, asking Buildbucket for the build status
[02:56:47.685] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:47.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:121:0) after 1m0s
[02:57:47.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:121:0)
[02:57:47.662] Timer tick, asking Buildbucket for the build status
[02:57:47.971] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:47.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:122:0) after 1m0s
[02:58:47.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:122:0)
[02:58:47.967] Timer tick, asking Buildbucket for the build status
[02:58:48.088] Build 8918056578233366400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:48.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083486640162913600:123:0) after 1m0s
[02:59:23.188] Received PubSub notification, asking Buildbucket for the build status
[02:59:23.262] Build 8918056578233366400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:59:23.262] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553482762983555",
"created_by": "project:fuchsia",
"created_ts": "1553475511289300",
"failure_reason": "BUILD_FAILURE",
"id": "8918056578233366400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b15c35aff878cd59328de80b95ba35ab0399b2c8\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-c-jhqq\", \"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\": \"b15c35aff878cd59328de80b95ba35ab0399b2c8\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-c-jhqq\"], \"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\": [\"4216-cf4a3cd\"], \"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": "1553475526025866",
"status": "COMPLETED",
"status_changed_ts": "1553475526404394",
"tags": [
"builder:clang",
"buildset:commit/git/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b15c35aff878cd59328de80b95ba35ab0399b2c8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083486640162913600",
"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/8918056578233366400/+/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:43cb2cad70b65310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553482763005046",
"url": "https://ci.chromium.org/b/8918056578233366400",
"utcnow_ts": "1553482763253874"
}
}
[02:59:23.262] Invocation finished in 2h0m53.197726183s with status FAILED