[14:41:46.156] New invocation is queued and will start shortly
[14:41:47.295] Starting the invocation (attempt 1)
[14:41:47.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:41:47.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:41:47.336] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9054625009313614800",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"abe01e17f648a97666d4fbed41f0861686a17972\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@abe01e17f648a97666d4fbed41f0861686a17972\",\"title\":\"abe01e17f648a97666d4fbed41f0861686a17972\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/abe01e17f648a97666d4fbed41f0861686a17972\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"e7cb374433095219069ef767f70c0c9159a65b7c\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@e7cb374433095219069ef767f70c0c9159a65b7c\",\"title\":\"e7cb374433095219069ef767f70c0c9159a65b7c\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/e7cb374433095219069ef767f70c0c9159a65b7c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\",\"title\":\"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0ea20ebf2d46072c36a0be49fdf4061480a42206\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0ea20ebf2d46072c36a0be49fdf4061480a42206\",\"title\":\"0ea20ebf2d46072c36a0be49fdf4061480a42206\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/0ea20ebf2d46072c36a0be49fdf4061480a42206\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@7603778922ab1e93ec3d9da451fcd89c3bad9ec2\",\"title\":\"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"09a88120c9269a9af0d80bc59afb2cb5806140ff\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@09a88120c9269a9af0d80bc59afb2cb5806140ff\",\"title\":\"09a88120c9269a9af0d80bc59afb2cb5806140ff\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/09a88120c9269a9af0d80bc59afb2cb5806140ff\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0a8cae10feb283b7a0886c5b52055de3db8b0e10\",\"title\":\"0a8cae10feb283b7a0886c5b52055de3db8b0e10\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9054625009313614800",
"scheduler_job_id:fuchsia/clang",
"user_agent:luci-scheduler",
"buildset:commit/git/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"gitiles_ref:refs/heads/master"
]
}
[14:41:47.862] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:fuchsia",
"created_ts": "1581000107465333",
"id": "8889194947273322208",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"abe01e17f648a97666d4fbed41f0861686a17972\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@abe01e17f648a97666d4fbed41f0861686a17972\", \"title\": \"abe01e17f648a97666d4fbed41f0861686a17972\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/abe01e17f648a97666d4fbed41f0861686a17972\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e7cb374433095219069ef767f70c0c9159a65b7c\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@e7cb374433095219069ef767f70c0c9159a65b7c\", \"title\": \"e7cb374433095219069ef767f70c0c9159a65b7c\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/e7cb374433095219069ef767f70c0c9159a65b7c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"title\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"title\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"title\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"title\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"title\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581000107748490",
"tags": [
"builder:clang",
"buildset:commit/git/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054625009313614800",
"scheduler_job_id:fuchsia/clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8889194947273322208/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1581000107748598",
"url": "https://ci.chromium.org/b/8889194947273322208",
"utcnow_ts": "1581000107855677"
}
}
[14:41:47.862] Task URL: https://ci.chromium.org/b/8889194947273322208
[14:41:47.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:2:0) after 1m0s
[14:41:58.864] Received PubSub notification, asking Buildbucket for the build status
[14:41:59.007] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:48.059] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:2:0)
[14:42:48.059] Timer tick, asking Buildbucket for the build status
[14:42:48.177] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:48.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:4:0) after 1m0s
[14:43:48.324] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:4:0)
[14:43:48.324] Timer tick, asking Buildbucket for the build status
[14:43:48.665] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:48.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:5:0) after 1m0s
[14:44:48.756] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:5:0)
[14:44:48.756] Timer tick, asking Buildbucket for the build status
[14:44:49.218] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:49.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:6:0) after 1m0s
[14:45:49.249] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:6:0)
[14:45:49.249] Timer tick, asking Buildbucket for the build status
[14:45:49.356] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:49.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:7:0) after 1m0s
[14:46:49.426] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:7:0)
[14:46:49.426] Timer tick, asking Buildbucket for the build status
[14:46:49.554] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:49.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:8:0) after 1m0s
[14:47:49.632] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:8:0)
[14:47:49.632] Timer tick, asking Buildbucket for the build status
[14:47:50.913] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:50.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:9:0) after 1m0s
[14:48:51.048] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:9:0)
[14:48:51.048] Timer tick, asking Buildbucket for the build status
[14:48:51.392] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:51.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:10:0) after 1m0s
[14:49:51.408] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:10:0)
[14:49:51.408] Timer tick, asking Buildbucket for the build status
[14:49:52.691] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:52.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:11:0) after 1m0s
[14:50:52.743] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:11:0)
[14:50:52.743] Timer tick, asking Buildbucket for the build status
[14:50:52.861] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:52.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:12:0) after 1m0s
[14:51:53.109] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:12:0)
[14:51:53.109] Timer tick, asking Buildbucket for the build status
[14:51:53.492] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:53.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:13:0) after 1m0s
[14:52:53.685] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:13:0)
[14:52:53.685] Timer tick, asking Buildbucket for the build status
[14:52:53.823] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:53.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:14:0) after 1m0s
[14:53:54.114] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:14:0)
[14:53:54.114] Timer tick, asking Buildbucket for the build status
[14:53:54.205] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:54.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:15:0) after 1m0s
[14:54:54.504] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:15:0)
[14:54:54.504] Timer tick, asking Buildbucket for the build status
[14:54:54.640] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:54.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:16:0) after 1m0s
[14:55:54.841] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:16:0)
[14:55:54.841] Timer tick, asking Buildbucket for the build status
[14:55:55.177] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:55.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:17:0) after 1m0s
[14:56:55.363] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:17:0)
[14:56:55.363] Timer tick, asking Buildbucket for the build status
[14:56:55.727] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:55.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:18:0) after 1m0s
[14:57:55.944] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:18:0)
[14:57:55.944] Timer tick, asking Buildbucket for the build status
[14:57:56.091] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:56.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:19:0) after 1m0s
[14:58:56.082] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:19:0)
[14:58:56.082] Timer tick, asking Buildbucket for the build status
[14:58:56.640] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:56.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:20:0) after 1m0s
[14:59:56.617] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:20:0)
[14:59:56.617] Timer tick, asking Buildbucket for the build status
[14:59:56.723] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:56.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:21:0) after 1m0s
[15:00:56.876] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:21:0)
[15:00:56.876] Timer tick, asking Buildbucket for the build status
[15:00:57.012] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:57.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:22:0) after 1m0s
[15:01:57.031] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:22:0)
[15:01:57.031] Timer tick, asking Buildbucket for the build status
[15:01:59.248] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:59.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:23:0) after 1m0s
[15:02:59.262] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:23:0)
[15:02:59.262] Timer tick, asking Buildbucket for the build status
[15:02:59.391] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:59.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:24:0) after 1m0s
[15:03:59.494] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:24:0)
[15:03:59.494] Timer tick, asking Buildbucket for the build status
[15:04:00.074] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:00.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:25:0) after 1m0s
[15:05:00.179] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:25:0)
[15:05:00.179] Timer tick, asking Buildbucket for the build status
[15:05:00.361] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:00.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:26:0) after 1m0s
[15:06:00.449] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:26:0)
[15:06:00.449] Timer tick, asking Buildbucket for the build status
[15:06:00.898] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:00.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:27:0) after 1m0s
[15:07:00.941] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:27:0)
[15:07:00.941] Timer tick, asking Buildbucket for the build status
[15:07:01.121] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:01.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:28:0) after 1m0s
[15:08:01.144] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:28:0)
[15:08:01.144] Timer tick, asking Buildbucket for the build status
[15:08:01.397] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:01.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:29:0) after 1m0s
[15:09:01.468] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:29:0)
[15:09:01.468] Timer tick, asking Buildbucket for the build status
[15:09:01.765] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:01.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:30:0) after 1m0s
[15:10:01.821] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:30:0)
[15:10:01.821] Timer tick, asking Buildbucket for the build status
[15:10:02.307] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:02.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:31:0) after 1m0s
[15:11:02.411] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:31:0)
[15:11:02.411] Timer tick, asking Buildbucket for the build status
[15:11:02.619] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:02.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:32:0) after 1m0s
[15:12:02.659] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:32:0)
[15:12:02.659] Timer tick, asking Buildbucket for the build status
[15:12:02.803] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:02.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:33:0) after 1m0s
[15:13:02.992] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:33:0)
[15:13:02.992] Timer tick, asking Buildbucket for the build status
[15:13:03.385] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:03.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:34:0) after 1m0s
[15:14:03.391] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:34:0)
[15:14:03.391] Timer tick, asking Buildbucket for the build status
[15:14:03.544] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:03.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:35:0) after 1m0s
[15:15:03.570] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:35:0)
[15:15:03.570] Timer tick, asking Buildbucket for the build status
[15:15:03.714] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:03.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:36:0) after 1m0s
[15:16:03.753] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:36:0)
[15:16:03.754] Timer tick, asking Buildbucket for the build status
[15:16:03.888] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:03.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:37:0) after 1m0s
[15:17:03.972] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:37:0)
[15:17:03.972] Timer tick, asking Buildbucket for the build status
[15:17:04.569] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:04.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:38:0) after 1m0s
[15:18:04.596] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:38:0)
[15:18:04.596] Timer tick, asking Buildbucket for the build status
[15:18:04.744] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:04.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:39:0) after 1m0s
[15:19:05.067] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:39:0)
[15:19:05.067] Timer tick, asking Buildbucket for the build status
[15:19:05.549] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:05.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:40:0) after 1m0s
[15:20:05.572] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:40:0)
[15:20:05.572] Timer tick, asking Buildbucket for the build status
[15:20:06.033] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:06.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:41:0) after 1m0s
[15:21:06.237] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:41:0)
[15:21:06.237] Timer tick, asking Buildbucket for the build status
[15:21:06.388] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:42:0) after 1m0s
[15:22:06.638] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:42:0)
[15:22:06.638] Timer tick, asking Buildbucket for the build status
[15:22:07.146] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:07.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:43:0) after 1m0s
[15:23:07.335] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:43:0)
[15:23:07.335] Timer tick, asking Buildbucket for the build status
[15:23:07.463] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:07.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:44:0) after 1m0s
[15:24:07.544] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:44:0)
[15:24:07.545] Timer tick, asking Buildbucket for the build status
[15:24:07.698] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:07.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:45:0) after 1m0s
[15:25:07.740] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:45:0)
[15:25:07.740] Timer tick, asking Buildbucket for the build status
[15:25:08.362] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:08.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:46:0) after 1m0s
[15:26:08.382] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:46:0)
[15:26:08.382] Timer tick, asking Buildbucket for the build status
[15:26:08.649] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:08.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:47:0) after 1m0s
[15:27:08.704] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:47:0)
[15:27:08.704] Timer tick, asking Buildbucket for the build status
[15:27:08.825] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:08.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:48:0) after 1m0s
[15:28:08.868] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:48:0)
[15:28:08.868] Timer tick, asking Buildbucket for the build status
[15:28:09.009] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:09.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:49:0) after 1m0s
[15:29:09.071] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:49:0)
[15:29:09.071] Timer tick, asking Buildbucket for the build status
[15:29:09.251] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:09.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:50:0) after 1m0s
[15:30:09.340] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:50:0)
[15:30:09.340] Timer tick, asking Buildbucket for the build status
[15:30:09.659] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:09.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:51:0) after 1m0s
[15:31:09.687] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:51:0)
[15:31:09.687] Timer tick, asking Buildbucket for the build status
[15:31:09.800] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:09.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:52:0) after 1m0s
[15:32:09.888] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:52:0)
[15:32:09.888] Timer tick, asking Buildbucket for the build status
[15:32:10.400] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:10.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:53:0) after 1m0s
[15:33:10.435] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:53:0)
[15:33:10.435] Timer tick, asking Buildbucket for the build status
[15:33:10.574] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:10.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:54:0) after 1m0s
[15:34:10.658] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:54:0)
[15:34:10.658] Timer tick, asking Buildbucket for the build status
[15:34:10.804] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:10.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:55:0) after 1m0s
[15:35:10.971] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:55:0)
[15:35:10.971] Timer tick, asking Buildbucket for the build status
[15:35:11.125] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:11.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:56:0) after 1m0s
[15:36:11.174] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:56:0)
[15:36:11.174] Timer tick, asking Buildbucket for the build status
[15:36:11.480] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:11.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:57:0) after 1m0s
[15:37:11.557] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:57:0)
[15:37:11.557] Timer tick, asking Buildbucket for the build status
[15:37:11.738] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:11.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:58:0) after 1m0s
[15:38:11.911] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:58:0)
[15:38:11.911] Timer tick, asking Buildbucket for the build status
[15:38:12.045] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:12.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:59:0) after 1m0s
[15:39:12.120] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:59:0)
[15:39:12.120] Timer tick, asking Buildbucket for the build status
[15:39:12.216] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:12.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:60:0) after 1m0s
[15:40:12.303] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:60:0)
[15:40:12.303] Timer tick, asking Buildbucket for the build status
[15:40:12.715] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:12.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:61:0) after 1m0s
[15:41:12.829] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:61:0)
[15:41:12.829] Timer tick, asking Buildbucket for the build status
[15:41:12.920] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:12.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:62:0) after 1m0s
[15:42:13.011] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:62:0)
[15:42:13.011] Timer tick, asking Buildbucket for the build status
[15:42:13.386] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:13.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:63:0) after 1m0s
[15:43:13.599] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:63:0)
[15:43:13.599] Timer tick, asking Buildbucket for the build status
[15:43:13.801] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:13.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:64:0) after 1m0s
[15:44:13.888] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:64:0)
[15:44:13.888] Timer tick, asking Buildbucket for the build status
[15:44:13.956] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:13.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:65:0) after 1m0s
[15:45:14.118] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:65:0)
[15:45:14.118] Timer tick, asking Buildbucket for the build status
[15:45:14.224] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:14.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:66:0) after 1m0s
[15:46:14.453] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:66:0)
[15:46:14.453] Timer tick, asking Buildbucket for the build status
[15:46:14.969] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:14.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:67:0) after 1m0s
[15:47:15.108] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:67:0)
[15:47:15.108] Timer tick, asking Buildbucket for the build status
[15:47:15.241] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:15.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:68:0) after 1m0s
[15:48:15.337] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:68:0)
[15:48:15.337] Timer tick, asking Buildbucket for the build status
[15:48:16.000] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:16.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:69:0) after 1m0s
[15:49:16.124] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:69:0)
[15:49:16.124] Timer tick, asking Buildbucket for the build status
[15:49:16.288] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:16.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:70:0) after 1m0s
[15:50:16.297] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:70:0)
[15:50:16.297] Timer tick, asking Buildbucket for the build status
[15:50:16.404] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:16.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:71:0) after 1m0s
[15:51:16.429] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:71:0)
[15:51:16.429] Timer tick, asking Buildbucket for the build status
[15:51:16.662] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:16.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:72:0) after 1m0s
[15:52:16.756] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:72:0)
[15:52:16.756] Timer tick, asking Buildbucket for the build status
[15:52:16.893] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:16.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:73:0) after 1m0s
[15:53:16.977] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:73:0)
[15:53:16.977] Timer tick, asking Buildbucket for the build status
[15:53:17.189] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:17.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:74:0) after 1m0s
[15:54:17.179] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:74:0)
[15:54:17.179] Timer tick, asking Buildbucket for the build status
[15:54:17.479] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:17.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:75:0) after 1m0s
[15:55:17.531] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:75:0)
[15:55:17.531] Timer tick, asking Buildbucket for the build status
[15:55:17.658] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:17.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:76:0) after 1m0s
[15:56:17.821] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:76:0)
[15:56:17.821] Timer tick, asking Buildbucket for the build status
[15:56:17.959] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:17.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:77:0) after 1m0s
[15:57:18.026] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:77:0)
[15:57:18.026] Timer tick, asking Buildbucket for the build status
[15:57:18.691] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:18.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:78:0) after 1m0s
[15:58:18.859] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:78:0)
[15:58:18.860] Timer tick, asking Buildbucket for the build status
[15:58:19.151] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:19.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:79:0) after 1m0s
[15:59:19.446] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:79:0)
[15:59:19.446] Timer tick, asking Buildbucket for the build status
[15:59:19.805] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:19.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:80:0) after 1m0s
[16:00:19.902] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:80:0)
[16:00:19.902] Timer tick, asking Buildbucket for the build status
[16:00:20.033] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:20.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:81:0) after 1m0s
[16:01:20.105] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:81:0)
[16:01:20.105] Timer tick, asking Buildbucket for the build status
[16:01:20.436] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:20.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:82:0) after 1m0s
[16:02:20.528] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:82:0)
[16:02:20.528] Timer tick, asking Buildbucket for the build status
[16:02:20.777] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:20.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:83:0) after 1m0s
[16:03:20.832] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:83:0)
[16:03:20.832] Timer tick, asking Buildbucket for the build status
[16:03:21.549] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:21.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:84:0) after 1m0s
[16:04:21.627] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:84:0)
[16:04:21.627] Timer tick, asking Buildbucket for the build status
[16:04:21.750] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:21.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:85:0) after 1m0s
[16:05:21.877] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:85:0)
[16:05:21.877] Timer tick, asking Buildbucket for the build status
[16:05:22.013] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:22.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:86:0) after 1m0s
[16:06:22.087] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:86:0)
[16:06:22.087] Timer tick, asking Buildbucket for the build status
[16:06:22.233] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:22.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:87:0) after 1m0s
[16:07:22.367] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:87:0)
[16:07:22.367] Timer tick, asking Buildbucket for the build status
[16:07:22.499] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:22.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:88:0) after 1m0s
[16:08:22.845] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:88:0)
[16:08:22.845] Timer tick, asking Buildbucket for the build status
[16:08:23.279] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:23.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:89:0) after 1m0s
[16:09:23.360] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:89:0)
[16:09:23.360] Timer tick, asking Buildbucket for the build status
[16:09:23.600] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:23.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:90:0) after 1m0s
[16:10:23.642] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:90:0)
[16:10:23.642] Timer tick, asking Buildbucket for the build status
[16:10:23.807] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:91:0) after 1m0s
[16:11:23.872] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:91:0)
[16:11:23.872] Timer tick, asking Buildbucket for the build status
[16:11:23.977] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:23.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:92:0) after 1m0s
[16:12:24.190] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:92:0)
[16:12:24.190] Timer tick, asking Buildbucket for the build status
[16:12:24.548] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:24.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:93:0) after 1m0s
[16:13:24.737] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:93:0)
[16:13:24.737] Timer tick, asking Buildbucket for the build status
[16:13:25.034] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:25.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:94:0) after 1m0s
[16:14:25.178] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:94:0)
[16:14:25.179] Timer tick, asking Buildbucket for the build status
[16:14:25.618] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:25.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:95:0) after 1m0s
[16:15:25.738] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:95:0)
[16:15:25.738] Timer tick, asking Buildbucket for the build status
[16:15:26.088] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:26.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:96:0) after 1m0s
[16:16:26.278] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:96:0)
[16:16:26.278] Timer tick, asking Buildbucket for the build status
[16:16:26.420] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:26.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:97:0) after 1m0s
[16:17:26.702] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:97:0)
[16:17:26.702] Timer tick, asking Buildbucket for the build status
[16:17:26.829] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:26.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:98:0) after 1m0s
[16:18:26.948] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:98:0)
[16:18:26.948] Timer tick, asking Buildbucket for the build status
[16:18:27.122] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:27.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:99:0) after 1m0s
[16:19:27.363] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:99:0)
[16:19:27.363] Timer tick, asking Buildbucket for the build status
[16:19:27.504] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:27.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:100:0) after 1m0s
[16:20:27.668] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:100:0)
[16:20:27.668] Timer tick, asking Buildbucket for the build status
[16:20:27.933] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:27.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:101:0) after 1m0s
[16:21:28.193] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:101:0)
[16:21:28.193] Timer tick, asking Buildbucket for the build status
[16:21:28.561] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:28.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:102:0) after 1m0s
[16:22:28.805] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:102:0)
[16:22:28.805] Timer tick, asking Buildbucket for the build status
[16:22:29.010] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:29.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:103:0) after 1m0s
[16:23:29.115] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:103:0)
[16:23:29.115] Timer tick, asking Buildbucket for the build status
[16:23:29.512] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:29.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:104:0) after 1m0s
[16:24:29.651] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:104:0)
[16:24:29.651] Timer tick, asking Buildbucket for the build status
[16:24:29.963] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:29.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:105:0) after 1m0s
[16:25:30.025] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:105:0)
[16:25:30.025] Timer tick, asking Buildbucket for the build status
[16:25:30.188] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:30.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:106:0) after 1m0s
[16:26:30.230] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:106:0)
[16:26:30.230] Timer tick, asking Buildbucket for the build status
[16:26:30.395] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:30.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:107:0) after 1m0s
[16:27:30.438] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:107:0)
[16:27:30.438] Timer tick, asking Buildbucket for the build status
[16:27:30.600] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:30.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:108:0) after 1m0s
[16:28:30.684] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:108:0)
[16:28:30.684] Timer tick, asking Buildbucket for the build status
[16:28:30.821] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:30.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:109:0) after 1m0s
[16:29:30.907] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:109:0)
[16:29:30.907] Timer tick, asking Buildbucket for the build status
[16:29:31.371] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:31.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:110:0) after 1m0s
[16:30:31.362] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:110:0)
[16:30:31.362] Timer tick, asking Buildbucket for the build status
[16:30:32.167] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:32.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:111:0) after 1m0s
[16:31:32.215] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:111:0)
[16:31:32.215] Timer tick, asking Buildbucket for the build status
[16:31:32.768] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:32.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:112:0) after 1m0s
[16:32:32.864] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:112:0)
[16:32:32.864] Timer tick, asking Buildbucket for the build status
[16:32:33.043] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:33.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:113:0) after 1m0s
[16:33:33.137] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:113:0)
[16:33:33.137] Timer tick, asking Buildbucket for the build status
[16:33:33.319] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:33.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:114:0) after 1m0s
[16:34:33.363] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:114:0)
[16:34:33.363] Timer tick, asking Buildbucket for the build status
[16:34:33.516] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:33.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:115:0) after 1m0s
[16:35:33.581] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:115:0)
[16:35:33.581] Timer tick, asking Buildbucket for the build status
[16:35:33.833] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:33.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:116:0) after 1m0s
[16:36:33.926] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:116:0)
[16:36:33.926] Timer tick, asking Buildbucket for the build status
[16:36:34.216] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:34.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:117:0) after 1m0s
[16:37:34.241] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:117:0)
[16:37:34.241] Timer tick, asking Buildbucket for the build status
[16:37:34.381] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:34.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:118:0) after 1m0s
[16:38:34.420] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:118:0)
[16:38:34.420] Timer tick, asking Buildbucket for the build status
[16:38:34.528] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:34.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:119:0) after 1m0s
[16:39:34.615] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:119:0)
[16:39:34.615] Timer tick, asking Buildbucket for the build status
[16:39:34.764] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:34.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:120:0) after 1m0s
[16:40:34.791] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:120:0)
[16:40:34.791] Timer tick, asking Buildbucket for the build status
[16:40:34.930] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:34.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:121:0) after 1m0s
[16:41:35.010] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:121:0)
[16:41:35.010] Timer tick, asking Buildbucket for the build status
[16:41:35.408] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:35.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:122:0) after 1m0s
[16:42:35.568] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:122:0)
[16:42:35.568] Timer tick, asking Buildbucket for the build status
[16:42:35.896] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:35.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:123:0) after 1m0s
[16:43:36.105] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:123:0)
[16:43:36.105] Timer tick, asking Buildbucket for the build status
[16:43:36.615] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:36.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:124:0) after 1m0s
[16:44:36.734] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:124:0)
[16:44:36.734] Timer tick, asking Buildbucket for the build status
[16:44:36.887] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:36.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:125:0) after 1m0s
[16:45:36.976] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:125:0)
[16:45:36.976] Timer tick, asking Buildbucket for the build status
[16:45:37.154] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:37.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:126:0) after 1m0s
[16:46:37.222] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:126:0)
[16:46:37.222] Timer tick, asking Buildbucket for the build status
[16:46:37.449] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:37.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:127:0) after 1m0s
[16:47:37.509] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:127:0)
[16:47:37.509] Timer tick, asking Buildbucket for the build status
[16:47:37.651] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:37.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:128:0) after 1m0s
[16:48:38.105] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:128:0)
[16:48:38.105] Timer tick, asking Buildbucket for the build status
[16:48:38.271] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:38.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:129:0) after 1m0s
[16:49:39.104] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:129:0)
[16:49:39.104] Timer tick, asking Buildbucket for the build status
[16:49:39.504] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:39.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:130:0) after 1m0s
[16:50:39.734] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:130:0)
[16:50:39.734] Timer tick, asking Buildbucket for the build status
[16:50:39.992] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:39.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:131:0) after 1m0s
[16:51:40.118] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:131:0)
[16:51:40.118] Timer tick, asking Buildbucket for the build status
[16:51:40.249] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:40.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:132:0) after 1m0s
[16:52:40.328] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:132:0)
[16:52:40.328] Timer tick, asking Buildbucket for the build status
[16:52:40.747] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:40.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:133:0) after 1m0s
[16:53:40.862] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:133:0)
[16:53:40.862] Timer tick, asking Buildbucket for the build status
[16:53:41.625] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:41.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:134:0) after 1m0s
[16:54:41.810] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:134:0)
[16:54:41.810] Timer tick, asking Buildbucket for the build status
[16:54:41.926] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:41.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:135:0) after 1m0s
[16:55:41.952] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:135:0)
[16:55:41.952] Timer tick, asking Buildbucket for the build status
[16:55:42.079] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:42.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:136:0) after 1m0s
[16:56:42.189] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:136:0)
[16:56:42.189] Timer tick, asking Buildbucket for the build status
[16:56:42.553] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:42.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:137:0) after 1m0s
[16:57:42.728] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:137:0)
[16:57:42.728] Timer tick, asking Buildbucket for the build status
[16:57:42.904] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:42.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:138:0) after 1m0s
[16:58:42.922] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:138:0)
[16:58:42.922] Timer tick, asking Buildbucket for the build status
[16:58:43.190] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:43.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:139:0) after 1m0s
[16:59:43.246] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:139:0)
[16:59:43.246] Timer tick, asking Buildbucket for the build status
[16:59:43.482] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:43.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:140:0) after 1m0s
[17:00:43.635] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:140:0)
[17:00:43.635] Timer tick, asking Buildbucket for the build status
[17:00:43.820] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:43.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:141:0) after 1m0s
[17:01:43.926] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:141:0)
[17:01:43.926] Timer tick, asking Buildbucket for the build status
[17:01:44.109] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:44.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:142:0) after 1m0s
[17:02:44.175] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:142:0)
[17:02:44.175] Timer tick, asking Buildbucket for the build status
[17:02:44.741] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:44.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:143:0) after 1m0s
[17:03:44.805] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:143:0)
[17:03:44.805] Timer tick, asking Buildbucket for the build status
[17:03:45.051] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:45.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:144:0) after 1m0s
[17:04:45.126] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:144:0)
[17:04:45.126] Timer tick, asking Buildbucket for the build status
[17:04:45.403] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:45.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:145:0) after 1m0s
[17:05:45.457] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:145:0)
[17:05:45.457] Timer tick, asking Buildbucket for the build status
[17:05:45.748] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:45.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:146:0) after 1m0s
[17:06:45.839] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:146:0)
[17:06:45.839] Timer tick, asking Buildbucket for the build status
[17:06:46.868] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:46.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:147:0) after 1m0s
[17:07:46.977] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:147:0)
[17:07:46.977] Timer tick, asking Buildbucket for the build status
[17:07:47.102] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:47.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:148:0) after 1m0s
[17:08:47.246] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:148:0)
[17:08:47.246] Timer tick, asking Buildbucket for the build status
[17:08:48.103] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:48.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:149:0) after 1m0s
[17:09:48.122] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:149:0)
[17:09:48.122] Timer tick, asking Buildbucket for the build status
[17:09:48.302] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:48.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:150:0) after 1m0s
[17:10:48.370] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:150:0)
[17:10:48.370] Timer tick, asking Buildbucket for the build status
[17:10:48.534] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:48.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:151:0) after 1m0s
[17:11:48.596] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:151:0)
[17:11:48.596] Timer tick, asking Buildbucket for the build status
[17:11:48.716] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:48.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:152:0) after 1m0s
[17:12:48.728] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:152:0)
[17:12:48.728] Timer tick, asking Buildbucket for the build status
[17:12:48.914] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:48.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:153:0) after 1m0s
[17:13:48.932] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:153:0)
[17:13:48.932] Timer tick, asking Buildbucket for the build status
[17:13:49.059] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:49.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:154:0) after 1m0s
[17:14:49.110] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:154:0)
[17:14:49.110] Timer tick, asking Buildbucket for the build status
[17:14:49.251] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:49.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:155:0) after 1m0s
[17:15:49.382] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:155:0)
[17:15:49.382] Timer tick, asking Buildbucket for the build status
[17:15:49.866] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:49.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:156:0) after 1m0s
[17:16:49.959] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:156:0)
[17:16:49.959] Timer tick, asking Buildbucket for the build status
[17:16:50.260] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:50.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:157:0) after 1m0s
[17:17:50.349] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:157:0)
[17:17:50.349] Timer tick, asking Buildbucket for the build status
[17:17:50.473] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:50.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:158:0) after 1m0s
[17:18:50.476] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:158:0)
[17:18:50.476] Timer tick, asking Buildbucket for the build status
[17:18:50.651] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:50.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:159:0) after 1m0s
[17:19:50.694] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:159:0)
[17:19:50.694] Timer tick, asking Buildbucket for the build status
[17:19:50.867] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:50.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:160:0) after 1m0s
[17:20:50.916] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:160:0)
[17:20:50.916] Timer tick, asking Buildbucket for the build status
[17:20:51.228] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:51.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:161:0) after 1m0s
[17:21:51.264] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:161:0)
[17:21:51.264] Timer tick, asking Buildbucket for the build status
[17:21:51.390] Build 8889194947273322208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:51.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054625009313614800:162:0) after 1m0s
[17:22:07.123] Received PubSub notification, asking Buildbucket for the build status
[17:22:07.214] Build 8889194947273322208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:22:07.214] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1581009725298910",
"created_by": "project:fuchsia",
"created_ts": "1581000107465333",
"id": "8889194947273322208",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"abe01e17f648a97666d4fbed41f0861686a17972\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@abe01e17f648a97666d4fbed41f0861686a17972\", \"title\": \"abe01e17f648a97666d4fbed41f0861686a17972\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/abe01e17f648a97666d4fbed41f0861686a17972\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e7cb374433095219069ef767f70c0c9159a65b7c\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@e7cb374433095219069ef767f70c0c9159a65b7c\", \"title\": \"e7cb374433095219069ef767f70c0c9159a65b7c\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/e7cb374433095219069ef767f70c0c9159a65b7c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"title\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"title\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"title\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"title\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"title\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"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/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"abe01e17f648a97666d4fbed41f0861686a17972\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@abe01e17f648a97666d4fbed41f0861686a17972\", \"title\": \"abe01e17f648a97666d4fbed41f0861686a17972\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/abe01e17f648a97666d4fbed41f0861686a17972\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e7cb374433095219069ef767f70c0c9159a65b7c\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@e7cb374433095219069ef767f70c0c9159a65b7c\", \"title\": \"e7cb374433095219069ef767f70c0c9159a65b7c\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/e7cb374433095219069ef767f70c0c9159a65b7c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"title\": \"89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/89a66474b6c1e5843c3dbc96bde52e5a7076c6cc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"title\": \"0ea20ebf2d46072c36a0be49fdf4061480a42206\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0ea20ebf2d46072c36a0be49fdf4061480a42206\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"title\": \"7603778922ab1e93ec3d9da451fcd89c3bad9ec2\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/7603778922ab1e93ec3d9da451fcd89c3bad9ec2\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"title\": \"09a88120c9269a9af0d80bc59afb2cb5806140ff\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/09a88120c9269a9af0d80bc59afb2cb5806140ff\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"title\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-f-1-edfo\", \"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\": \"0a8cae10feb283b7a0886c5b52055de3db8b0e10\", \"timeout\": 21600.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-f-1-edfo\"], \"image\": [\"fuchsia-debian-9-drawfork-v20200123-597b1f5\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.11\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"scale_group\": [\"fuchsia-prod-1\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1581000117506748",
"status": "COMPLETED",
"status_changed_ts": "1581009725851673",
"tags": [
"builder:clang",
"buildset:commit/git/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0a8cae10feb283b7a0886c5b52055de3db8b0e10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054625009313614800",
"scheduler_job_id:fuchsia/clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8889194947273322208/+/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:4a33c4b159efb010",
"user_agent:luci-scheduler"
],
"updated_ts": "1581009726238030",
"url": "https://ci.chromium.org/b/8889194947273322208",
"utcnow_ts": "1581009727207168"
}
}
[17:22:07.214] Invocation finished in 2h40m21.078527725s with status SUCCEEDED