[03:02:30.171] New invocation is queued and will start shortly
[03:02:31.280] Starting the invocation (attempt 1)
[03:02:31.335] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:31.335] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:31.335] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084384808329802688",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"2f741868ed7722466e70f13cab044d4ac94a30dd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084384808329802688",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/2f741868ed7722466e70f13cab044d4ac94a30dd",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2f741868ed7722466e70f13cab044d4ac94a30dd",
"gitiles_ref:refs/heads/master"
]
}
[03:02:32.619] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552618951424378",
"id": "8918954746349813680",
"lease_expiration_ts": "1552744952177341",
"lease_key": "254629611",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2f741868ed7722466e70f13cab044d4ac94a30dd\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552618952403044",
"tags": [
"builder:clang",
"buildset:commit/git/2f741868ed7722466e70f13cab044d4ac94a30dd",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2f741868ed7722466e70f13cab044d4ac94a30dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084384808329802688",
"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/8918954746349813680/+/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:43981e9d04158a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552618952403731",
"url": "https://ci.chromium.org/b/8918954746349813680",
"utcnow_ts": "1552618952597855"
}
}
[03:02:32.619] Task URL: https://ci.chromium.org/b/8918954746349813680
[03:02:32.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:2:0) after 1m0s
[03:03:02.928] Received PubSub notification, asking Buildbucket for the build status
[03:03:02.978] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:2:0)
[03:03:32.641] Timer tick, asking Buildbucket for the build status
[03:03:32.710] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:4:0) after 1m0s
[03:04:32.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:4:0)
[03:04:32.716] Timer tick, asking Buildbucket for the build status
[03:04:32.800] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:32.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:5:0) after 1m0s
[03:05:32.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:5:0)
[03:05:32.795] Timer tick, asking Buildbucket for the build status
[03:05:32.850] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:32.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:6:0) after 1m0s
[03:06:32.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:6:0)
[03:06:32.850] Timer tick, asking Buildbucket for the build status
[03:06:33.009] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:33.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:7:0) after 1m0s
[03:07:33.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:7:0)
[03:07:33.026] Timer tick, asking Buildbucket for the build status
[03:07:33.143] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:33.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:8:0) after 1m0s
[03:08:33.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:8:0)
[03:08:33.159] Timer tick, asking Buildbucket for the build status
[03:08:33.273] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:33.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:9:0) after 1m0s
[03:09:33.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:9:0)
[03:09:33.291] Timer tick, asking Buildbucket for the build status
[03:09:33.493] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:33.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:10:0) after 1m0s
[03:10:33.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:10:0)
[03:10:33.490] Timer tick, asking Buildbucket for the build status
[03:10:33.687] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:33.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:11:0) after 1m0s
[03:11:33.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:11:0)
[03:11:33.671] Timer tick, asking Buildbucket for the build status
[03:11:33.765] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:33.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:12:0) after 1m0s
[03:12:33.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:12:0)
[03:12:33.743] Timer tick, asking Buildbucket for the build status
[03:12:33.961] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:33.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:13:0) after 1m0s
[03:13:33.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:13:0)
[03:13:33.979] Timer tick, asking Buildbucket for the build status
[03:13:34.064] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:34.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:14:0) after 1m0s
[03:14:34.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:14:0)
[03:14:34.087] Timer tick, asking Buildbucket for the build status
[03:14:34.147] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:34.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:15:0) after 1m0s
[03:15:34.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:15:0)
[03:15:34.135] Timer tick, asking Buildbucket for the build status
[03:15:34.205] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:34.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:16:0) after 1m0s
[03:16:34.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:16:0)
[03:16:34.230] Timer tick, asking Buildbucket for the build status
[03:16:34.436] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:34.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:17:0) after 1m0s
[03:17:34.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:17:0)
[03:17:34.453] Timer tick, asking Buildbucket for the build status
[03:17:34.647] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:34.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:18:0) after 1m0s
[03:18:34.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:18:0)
[03:18:34.665] Timer tick, asking Buildbucket for the build status
[03:18:34.756] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:34.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:19:0) after 1m0s
[03:19:34.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:19:0)
[03:19:34.775] Timer tick, asking Buildbucket for the build status
[03:19:34.855] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:34.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:20:0) after 1m0s
[03:20:34.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:20:0)
[03:20:34.848] Timer tick, asking Buildbucket for the build status
[03:20:34.925] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:34.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:21:0) after 1m0s
[03:21:34.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:21:0)
[03:21:34.950] Timer tick, asking Buildbucket for the build status
[03:21:34.999] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:34.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:22:0) after 1m0s
[03:22:34.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:22:0)
[03:22:34.996] Timer tick, asking Buildbucket for the build status
[03:22:35.062] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:35.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:23:0) after 1m0s
[03:23:35.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:23:0)
[03:23:35.066] Timer tick, asking Buildbucket for the build status
[03:23:35.112] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:35.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:24:0) after 1m0s
[03:24:35.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:24:0)
[03:24:35.151] Timer tick, asking Buildbucket for the build status
[03:24:35.247] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:35.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:25:0) after 1m0s
[03:25:35.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:25:0)
[03:25:35.265] Timer tick, asking Buildbucket for the build status
[03:25:35.309] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:35.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:26:0) after 1m0s
[03:26:35.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:26:0)
[03:26:35.325] Timer tick, asking Buildbucket for the build status
[03:26:35.573] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:35.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:27:0) after 1m0s
[03:27:35.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:27:0)
[03:27:35.587] Timer tick, asking Buildbucket for the build status
[03:27:35.644] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:35.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:28:0) after 1m0s
[03:28:35.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:28:0)
[03:28:35.663] Timer tick, asking Buildbucket for the build status
[03:28:35.727] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:35.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:29:0) after 1m0s
[03:29:35.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:29:0)
[03:29:35.769] Timer tick, asking Buildbucket for the build status
[03:29:35.905] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:35.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:30:0) after 1m0s
[03:30:35.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:30:0)
[03:30:35.925] Timer tick, asking Buildbucket for the build status
[03:30:35.990] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:35.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:31:0) after 1m0s
[03:31:35.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:31:0)
[03:31:35.997] Timer tick, asking Buildbucket for the build status
[03:31:36.077] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:36.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:32:0) after 1m0s
[03:32:36.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:32:0)
[03:32:36.064] Timer tick, asking Buildbucket for the build status
[03:32:36.198] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:33:0) after 1m0s
[03:33:36.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:33:0)
[03:33:36.216] Timer tick, asking Buildbucket for the build status
[03:33:36.369] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:36.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:34:0) after 1m0s
[03:34:36.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:34:0)
[03:34:36.366] Timer tick, asking Buildbucket for the build status
[03:34:36.423] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:36.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:35:0) after 1m0s
[03:35:36.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:35:0)
[03:35:36.401] Timer tick, asking Buildbucket for the build status
[03:35:36.510] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:36.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:36:0) after 1m0s
[03:36:36.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:36:0)
[03:36:36.530] Timer tick, asking Buildbucket for the build status
[03:36:36.603] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:36.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:37:0) after 1m0s
[03:37:36.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:37:0)
[03:37:36.586] Timer tick, asking Buildbucket for the build status
[03:37:36.677] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:36.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:38:0) after 1m0s
[03:38:36.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:38:0)
[03:38:36.653] Timer tick, asking Buildbucket for the build status
[03:38:36.782] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:36.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:39:0) after 1m0s
[03:39:36.787] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:39:0)
[03:39:36.787] Timer tick, asking Buildbucket for the build status
[03:39:36.841] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:36.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:40:0) after 1m0s
[03:40:36.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:40:0)
[03:40:36.878] Timer tick, asking Buildbucket for the build status
[03:40:36.936] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:36.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:41:0) after 1m0s
[03:41:36.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:41:0)
[03:41:36.955] Timer tick, asking Buildbucket for the build status
[03:41:36.996] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:36.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:42:0) after 1m0s
[03:42:37.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:42:0)
[03:42:37.016] Timer tick, asking Buildbucket for the build status
[03:42:37.099] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:37.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:43:0) after 1m0s
[03:43:37.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:43:0)
[03:43:37.088] Timer tick, asking Buildbucket for the build status
[03:43:37.197] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:37.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:44:0) after 1m0s
[03:44:37.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:44:0)
[03:44:37.207] Timer tick, asking Buildbucket for the build status
[03:44:37.593] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:37.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:45:0) after 1m0s
[03:45:37.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:45:0)
[03:45:37.615] Timer tick, asking Buildbucket for the build status
[03:45:37.880] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:37.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:46:0) after 1m0s
[03:46:37.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:46:0)
[03:46:37.903] Timer tick, asking Buildbucket for the build status
[03:46:37.960] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:37.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:47:0) after 1m0s
[03:47:38.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:47:0)
[03:47:38.000] Timer tick, asking Buildbucket for the build status
[03:47:38.159] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:38.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:48:0) after 1m0s
[03:48:38.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:48:0)
[03:48:38.181] Timer tick, asking Buildbucket for the build status
[03:48:38.571] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:38.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:49:0) after 1m0s
[03:49:38.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:49:0)
[03:49:38.609] Timer tick, asking Buildbucket for the build status
[03:49:38.889] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:38.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:50:0) after 1m0s
[03:50:38.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:50:0)
[03:50:38.925] Timer tick, asking Buildbucket for the build status
[03:50:39.017] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:51:0) after 1m0s
[03:51:39.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:51:0)
[03:51:39.041] Timer tick, asking Buildbucket for the build status
[03:51:39.164] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:39.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:52:0) after 1m0s
[03:52:39.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:52:0)
[03:52:39.184] Timer tick, asking Buildbucket for the build status
[03:52:39.411] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:39.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:53:0) after 1m0s
[03:53:39.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:53:0)
[03:53:39.433] Timer tick, asking Buildbucket for the build status
[03:53:39.581] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:39.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:54:0) after 1m0s
[03:54:39.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:54:0)
[03:54:39.601] Timer tick, asking Buildbucket for the build status
[03:54:39.716] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:39.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:55:0) after 1m0s
[03:55:39.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:55:0)
[03:55:39.703] Timer tick, asking Buildbucket for the build status
[03:55:39.746] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:39.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:56:0) after 1m0s
[03:56:39.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:56:0)
[03:56:39.736] Timer tick, asking Buildbucket for the build status
[03:56:39.797] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:39.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:57:0) after 1m0s
[03:57:39.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:57:0)
[03:57:39.799] Timer tick, asking Buildbucket for the build status
[03:57:39.849] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:39.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:58:0) after 1m0s
[03:58:39.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:58:0)
[03:58:39.864] Timer tick, asking Buildbucket for the build status
[03:58:39.922] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:39.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:59:0) after 1m0s
[03:59:39.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:59:0)
[03:59:39.901] Timer tick, asking Buildbucket for the build status
[03:59:40.001] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:60:0) after 1m0s
[04:00:39.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:60:0)
[04:00:39.996] Timer tick, asking Buildbucket for the build status
[04:00:40.082] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:61:0) after 1m0s
[04:01:40.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:61:0)
[04:01:40.086] Timer tick, asking Buildbucket for the build status
[04:01:40.187] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:40.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:62:0) after 1m0s
[04:02:40.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:62:0)
[04:02:40.204] Timer tick, asking Buildbucket for the build status
[04:02:40.605] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:40.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:63:0) after 1m0s
[04:03:40.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:63:0)
[04:03:40.589] Timer tick, asking Buildbucket for the build status
[04:03:40.678] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:40.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:64:0) after 1m0s
[04:04:40.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:64:0)
[04:04:40.699] Timer tick, asking Buildbucket for the build status
[04:04:40.760] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:40.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:65:0) after 1m0s
[04:05:40.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:65:0)
[04:05:40.779] Timer tick, asking Buildbucket for the build status
[04:05:40.822] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:40.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:66:0) after 1m0s
[04:06:40.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:66:0)
[04:06:40.813] Timer tick, asking Buildbucket for the build status
[04:06:40.874] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:40.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:67:0) after 1m0s
[04:07:40.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:67:0)
[04:07:40.899] Timer tick, asking Buildbucket for the build status
[04:07:40.945] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:40.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:68:0) after 1m0s
[04:08:40.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:68:0)
[04:08:40.988] Timer tick, asking Buildbucket for the build status
[04:08:41.085] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:41.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:69:0) after 1m0s
[04:09:41.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:69:0)
[04:09:41.108] Timer tick, asking Buildbucket for the build status
[04:09:41.199] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:41.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:70:0) after 1m0s
[04:10:41.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:70:0)
[04:10:41.198] Timer tick, asking Buildbucket for the build status
[04:10:41.310] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:41.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:71:0) after 1m0s
[04:11:41.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:71:0)
[04:11:41.287] Timer tick, asking Buildbucket for the build status
[04:11:41.477] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:41.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:72:0) after 1m0s
[04:12:41.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:72:0)
[04:12:41.482] Timer tick, asking Buildbucket for the build status
[04:12:41.530] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:41.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:73:0) after 1m0s
[04:13:41.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:73:0)
[04:13:41.543] Timer tick, asking Buildbucket for the build status
[04:13:41.680] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:41.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:74:0) after 1m0s
[04:14:41.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:74:0)
[04:14:41.700] Timer tick, asking Buildbucket for the build status
[04:14:41.910] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:41.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:75:0) after 1m0s
[04:15:41.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:75:0)
[04:15:41.933] Timer tick, asking Buildbucket for the build status
[04:15:42.198] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:42.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:76:0) after 1m0s
[04:16:42.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:76:0)
[04:16:42.224] Timer tick, asking Buildbucket for the build status
[04:16:42.404] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:42.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:77:0) after 1m0s
[04:17:42.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:77:0)
[04:17:42.395] Timer tick, asking Buildbucket for the build status
[04:17:42.465] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:42.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:78:0) after 1m0s
[04:18:42.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:78:0)
[04:18:42.444] Timer tick, asking Buildbucket for the build status
[04:18:42.555] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:42.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:79:0) after 1m0s
[04:19:42.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:79:0)
[04:19:42.581] Timer tick, asking Buildbucket for the build status
[04:19:42.676] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:42.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:80:0) after 1m0s
[04:20:42.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:80:0)
[04:20:42.675] Timer tick, asking Buildbucket for the build status
[04:20:42.759] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:42.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:81:0) after 1m0s
[04:21:42.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:81:0)
[04:21:42.733] Timer tick, asking Buildbucket for the build status
[04:21:42.907] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:42.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:82:0) after 1m0s
[04:22:42.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:82:0)
[04:22:42.901] Timer tick, asking Buildbucket for the build status
[04:22:43.086] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:43.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:83:0) after 1m0s
[04:23:43.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:83:0)
[04:23:43.107] Timer tick, asking Buildbucket for the build status
[04:23:43.168] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:43.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:84:0) after 1m0s
[04:24:43.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:84:0)
[04:24:43.193] Timer tick, asking Buildbucket for the build status
[04:24:43.235] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:43.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:85:0) after 1m0s
[04:25:43.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:85:0)
[04:25:43.255] Timer tick, asking Buildbucket for the build status
[04:25:43.362] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:43.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:86:0) after 1m0s
[04:26:43.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:86:0)
[04:26:43.360] Timer tick, asking Buildbucket for the build status
[04:26:43.686] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:43.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:87:0) after 1m0s
[04:27:43.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:87:0)
[04:27:43.676] Timer tick, asking Buildbucket for the build status
[04:27:43.722] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:43.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:88:0) after 1m0s
[04:28:43.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:88:0)
[04:28:43.743] Timer tick, asking Buildbucket for the build status
[04:28:43.868] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:43.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:89:0) after 1m0s
[04:29:43.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:89:0)
[04:29:43.873] Timer tick, asking Buildbucket for the build status
[04:29:43.981] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:43.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:90:0) after 1m0s
[04:30:43.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:90:0)
[04:30:43.983] Timer tick, asking Buildbucket for the build status
[04:30:44.031] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:44.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:91:0) after 1m0s
[04:31:44.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:91:0)
[04:31:44.064] Timer tick, asking Buildbucket for the build status
[04:31:44.195] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:44.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:92:0) after 1m0s
[04:32:44.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:92:0)
[04:32:44.218] Timer tick, asking Buildbucket for the build status
[04:32:44.369] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:44.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:93:0) after 1m0s
[04:33:44.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:93:0)
[04:33:44.378] Timer tick, asking Buildbucket for the build status
[04:33:44.456] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:44.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:94:0) after 1m0s
[04:34:44.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:94:0)
[04:34:44.441] Timer tick, asking Buildbucket for the build status
[04:34:44.483] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:44.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:95:0) after 1m0s
[04:35:44.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:95:0)
[04:35:44.491] Timer tick, asking Buildbucket for the build status
[04:35:44.679] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:44.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:96:0) after 1m0s
[04:36:44.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:96:0)
[04:36:44.672] Timer tick, asking Buildbucket for the build status
[04:36:44.861] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:44.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:97:0) after 1m0s
[04:37:44.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:97:0)
[04:37:44.887] Timer tick, asking Buildbucket for the build status
[04:37:44.945] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:44.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:98:0) after 1m0s
[04:38:44.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:98:0)
[04:38:44.968] Timer tick, asking Buildbucket for the build status
[04:38:45.115] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:45.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:99:0) after 1m0s
[04:39:45.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:99:0)
[04:39:45.131] Timer tick, asking Buildbucket for the build status
[04:39:45.392] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:45.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:100:0) after 1m0s
[04:40:45.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:100:0)
[04:40:45.390] Timer tick, asking Buildbucket for the build status
[04:40:45.547] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:45.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:101:0) after 1m0s
[04:41:45.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:101:0)
[04:41:45.569] Timer tick, asking Buildbucket for the build status
[04:41:45.609] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:45.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:102:0) after 1m0s
[04:42:45.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:102:0)
[04:42:45.606] Timer tick, asking Buildbucket for the build status
[04:42:45.737] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:45.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:103:0) after 1m0s
[04:43:45.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:103:0)
[04:43:45.760] Timer tick, asking Buildbucket for the build status
[04:43:45.854] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:45.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:104:0) after 1m0s
[04:44:45.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:104:0)
[04:44:45.875] Timer tick, asking Buildbucket for the build status
[04:44:45.942] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:45.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:105:0) after 1m0s
[04:45:45.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:105:0)
[04:45:45.967] Timer tick, asking Buildbucket for the build status
[04:45:46.343] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:46.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:106:0) after 1m0s
[04:46:46.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:106:0)
[04:46:46.342] Timer tick, asking Buildbucket for the build status
[04:46:46.485] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:46.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:107:0) after 1m0s
[04:47:46.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:107:0)
[04:47:46.506] Timer tick, asking Buildbucket for the build status
[04:47:46.584] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:46.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:108:0) after 1m0s
[04:48:46.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:108:0)
[04:48:46.621] Timer tick, asking Buildbucket for the build status
[04:48:46.689] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:46.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:109:0) after 1m0s
[04:49:46.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:109:0)
[04:49:46.691] Timer tick, asking Buildbucket for the build status
[04:49:46.964] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:46.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:110:0) after 1m0s
[04:50:46.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:110:0)
[04:50:46.989] Timer tick, asking Buildbucket for the build status
[04:50:47.038] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:47.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:111:0) after 1m0s
[04:51:47.051] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:111:0)
[04:51:47.051] Timer tick, asking Buildbucket for the build status
[04:51:47.113] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:47.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:112:0) after 1m0s
[04:52:47.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:112:0)
[04:52:47.133] Timer tick, asking Buildbucket for the build status
[04:52:47.261] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:47.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:113:0) after 1m0s
[04:53:47.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:113:0)
[04:53:47.289] Timer tick, asking Buildbucket for the build status
[04:53:47.401] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:47.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:114:0) after 1m0s
[04:54:47.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:114:0)
[04:54:47.423] Timer tick, asking Buildbucket for the build status
[04:54:47.481] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:47.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:115:0) after 1m0s
[04:55:47.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:115:0)
[04:55:47.501] Timer tick, asking Buildbucket for the build status
[04:55:47.546] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:47.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:116:0) after 1m0s
[04:56:47.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:116:0)
[04:56:47.567] Timer tick, asking Buildbucket for the build status
[04:56:47.677] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:47.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:117:0) after 1m0s
[04:57:47.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:117:0)
[04:57:47.669] Timer tick, asking Buildbucket for the build status
[04:57:47.876] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:47.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:118:0) after 1m0s
[04:58:47.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:118:0)
[04:58:47.882] Timer tick, asking Buildbucket for the build status
[04:58:47.922] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:47.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:119:0) after 1m0s
[04:59:47.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:119:0)
[04:59:47.945] Timer tick, asking Buildbucket for the build status
[04:59:48.089] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:48.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:120:0) after 1m0s
[05:00:48.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:120:0)
[05:00:48.097] Timer tick, asking Buildbucket for the build status
[05:00:49.159] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:49.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:121:0) after 1m0s
[05:01:49.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:121:0)
[05:01:49.168] Timer tick, asking Buildbucket for the build status
[05:01:49.239] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:49.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:122:0) after 1m0s
[05:02:49.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:122:0)
[05:02:49.256] Timer tick, asking Buildbucket for the build status
[05:02:49.303] Build 8918954746349813680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:49.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084384808329802688:123:0) after 1m0s
[05:03:32.418] Received PubSub notification, asking Buildbucket for the build status
[05:03:32.454] Build 8918954746349813680: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:03:32.454] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1552626211675382",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552618951424378",
"failure_reason": "BUILD_FAILURE",
"id": "8918954746349813680",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2f741868ed7722466e70f13cab044d4ac94a30dd\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-b-h116\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"timeout\": 7200.0, \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"2f741868ed7722466e70f13cab044d4ac94a30dd\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-b-h116\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552618982254488",
"status": "COMPLETED",
"status_changed_ts": "1552626212234843",
"tags": [
"builder:clang",
"buildset:commit/git/2f741868ed7722466e70f13cab044d4ac94a30dd",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2f741868ed7722466e70f13cab044d4ac94a30dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084384808329802688",
"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/8918954746349813680/+/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:43981e9d04158a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552626212237647",
"url": "https://ci.chromium.org/b/8918954746349813680",
"utcnow_ts": "1552626212446637"
}
}
[05:03:32.454] Invocation finished in 2h1m2.302482256s with status FAILED