[11:26:46.602] New invocation is queued and will start shortly
[11:26:47.728] Starting the invocation (attempt 1)
[11:26:47.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:26:47.795] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:26:47.795] Buildbucket request:
{
"bucket": "luci.nacl.ci",
"client_operation_id": "9021931772310256528",
"parameters_json": "{\"builder_name\":\"linux-64-validator-opt\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\",\"title\":\"952e81813a66902714b68f34b51073c5d1ba6c94\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-64-validator-opt",
"scheduler_invocation_id:9021931772310256528",
"scheduler_job_id:nacl/linux-64-validator-opt",
"user_agent:luci-scheduler",
"buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
"gitiles_ref:refs/heads/master"
]
}
[11:26:49.820] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1612178808106524",
"id": "8856501710069382992",
"parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612178809443051",
"tags": [
"build_address:luci.nacl.ci/linux-64-validator-opt/159",
"builder:linux-64-validator-opt",
"buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021931772310256528",
"scheduler_job_id:nacl/linux-64-validator-opt",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856501710069382992/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612178809443547",
"url": "https://ci.chromium.org/b/8856501710069382992",
"utcnow_ts": "1612178809798588"
}
}
[11:26:49.820] Task URL: https://ci.chromium.org/b/8856501710069382992
[11:26:49.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:2:0) after 1m0s
[11:27:35.422] Received PubSub notification, asking Buildbucket for the build status
[11:27:35.644] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:50.182] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:2:0)
[11:27:50.182] Timer tick, asking Buildbucket for the build status
[11:27:50.280] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:50.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:4:0) after 1m0s
[11:28:50.324] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:4:0)
[11:28:50.324] Timer tick, asking Buildbucket for the build status
[11:28:50.422] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:50.422] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:5:0) after 1m0s
[11:29:50.462] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:5:0)
[11:29:50.462] Timer tick, asking Buildbucket for the build status
[11:29:50.633] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:50.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:6:0) after 1m0s
[11:30:50.638] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:6:0)
[11:30:50.638] Timer tick, asking Buildbucket for the build status
[11:30:50.970] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:50.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:7:0) after 1m0s
[11:31:50.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:7:0)
[11:31:50.967] Timer tick, asking Buildbucket for the build status
[11:31:51.069] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:51.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:8:0) after 1m0s
[11:32:51.120] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:8:0)
[11:32:51.120] Timer tick, asking Buildbucket for the build status
[11:32:51.615] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:51.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:9:0) after 1m0s
[11:33:51.698] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:9:0)
[11:33:51.698] Timer tick, asking Buildbucket for the build status
[11:33:51.910] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:51.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:10:0) after 1m0s
[11:34:51.943] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:10:0)
[11:34:51.943] Timer tick, asking Buildbucket for the build status
[11:34:52.046] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:52.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:11:0) after 1m0s
[11:35:52.039] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:11:0)
[11:35:52.039] Timer tick, asking Buildbucket for the build status
[11:35:52.246] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:52.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:12:0) after 1m0s
[11:36:52.465] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:12:0)
[11:36:52.466] Timer tick, asking Buildbucket for the build status
[11:36:52.610] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:52.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:13:0) after 1m0s
[11:37:52.628] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:13:0)
[11:37:52.628] Timer tick, asking Buildbucket for the build status
[11:37:52.876] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:52.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:14:0) after 1m0s
[11:38:52.970] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:14:0)
[11:38:52.970] Timer tick, asking Buildbucket for the build status
[11:38:53.069] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:53.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:15:0) after 1m0s
[11:39:53.218] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:15:0)
[11:39:53.218] Timer tick, asking Buildbucket for the build status
[11:39:53.288] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:53.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:16:0) after 1m0s
[11:40:53.291] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:16:0)
[11:40:53.291] Timer tick, asking Buildbucket for the build status
[11:40:53.373] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:53.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:17:0) after 1m0s
[11:41:53.432] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:17:0)
[11:41:53.432] Timer tick, asking Buildbucket for the build status
[11:41:53.547] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:53.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:18:0) after 1m0s
[11:42:53.633] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:18:0)
[11:42:53.633] Timer tick, asking Buildbucket for the build status
[11:42:53.687] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:53.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:19:0) after 1m0s
[11:43:53.803] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:19:0)
[11:43:53.803] Timer tick, asking Buildbucket for the build status
[11:43:53.879] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:53.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:20:0) after 1m0s
[11:44:53.938] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:20:0)
[11:44:53.938] Timer tick, asking Buildbucket for the build status
[11:44:54.154] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:54.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:21:0) after 1m0s
[11:45:54.237] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:21:0)
[11:45:54.237] Timer tick, asking Buildbucket for the build status
[11:45:54.509] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:54.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:22:0) after 1m0s
[11:46:54.554] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:22:0)
[11:46:54.554] Timer tick, asking Buildbucket for the build status
[11:46:54.647] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:54.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:23:0) after 1m0s
[11:47:54.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:23:0)
[11:47:54.679] Timer tick, asking Buildbucket for the build status
[11:47:55.079] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:55.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:24:0) after 1m0s
[11:48:55.101] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:24:0)
[11:48:55.101] Timer tick, asking Buildbucket for the build status
[11:48:55.237] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:55.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:25:0) after 1m0s
[11:49:55.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:25:0)
[11:49:55.281] Timer tick, asking Buildbucket for the build status
[11:49:55.355] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:55.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:26:0) after 1m0s
[11:50:55.376] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:26:0)
[11:50:55.376] Timer tick, asking Buildbucket for the build status
[11:50:55.703] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:55.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:27:0) after 1m0s
[11:51:55.743] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:27:0)
[11:51:55.743] Timer tick, asking Buildbucket for the build status
[11:51:55.868] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:55.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:28:0) after 1m0s
[11:52:55.914] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:28:0)
[11:52:55.914] Timer tick, asking Buildbucket for the build status
[11:52:56.050] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:56.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:29:0) after 1m0s
[11:53:56.038] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:29:0)
[11:53:56.038] Timer tick, asking Buildbucket for the build status
[11:53:56.144] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:56.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:30:0) after 1m0s
[11:54:56.198] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:30:0)
[11:54:56.198] Timer tick, asking Buildbucket for the build status
[11:54:56.294] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:56.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:31:0) after 1m0s
[11:55:56.311] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:31:0)
[11:55:56.311] Timer tick, asking Buildbucket for the build status
[11:55:56.395] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:56.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:32:0) after 1m0s
[11:56:56.437] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:32:0)
[11:56:56.437] Timer tick, asking Buildbucket for the build status
[11:56:56.610] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:56.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:33:0) after 1m0s
[11:57:56.633] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:33:0)
[11:57:56.633] Timer tick, asking Buildbucket for the build status
[11:57:56.724] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:56.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:34:0) after 1m0s
[11:58:56.776] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:34:0)
[11:58:56.776] Timer tick, asking Buildbucket for the build status
[11:58:56.842] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:56.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:35:0) after 1m0s
[11:59:56.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:35:0)
[11:59:56.898] Timer tick, asking Buildbucket for the build status
[11:59:56.966] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:56.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:36:0) after 1m0s
[12:00:57.036] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:36:0)
[12:00:57.036] Timer tick, asking Buildbucket for the build status
[12:00:57.204] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:57.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:37:0) after 1m0s
[12:01:57.288] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:37:0)
[12:01:57.288] Timer tick, asking Buildbucket for the build status
[12:01:57.433] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:57.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:38:0) after 1m0s
[12:02:57.457] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:38:0)
[12:02:57.457] Timer tick, asking Buildbucket for the build status
[12:02:57.534] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:57.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:39:0) after 1m0s
[12:03:57.598] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:39:0)
[12:03:57.598] Timer tick, asking Buildbucket for the build status
[12:03:57.695] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:57.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:40:0) after 1m0s
[12:04:57.790] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:40:0)
[12:04:57.790] Timer tick, asking Buildbucket for the build status
[12:04:58.421] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:58.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:41:0) after 1m0s
[12:05:58.436] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:41:0)
[12:05:58.436] Timer tick, asking Buildbucket for the build status
[12:05:58.649] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:58.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:42:0) after 1m0s
[12:06:58.716] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:42:0)
[12:06:58.716] Timer tick, asking Buildbucket for the build status
[12:06:58.827] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:58.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:43:0) after 1m0s
[12:07:58.942] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:43:0)
[12:07:58.942] Timer tick, asking Buildbucket for the build status
[12:07:59.421] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:59.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:44:0) after 1m0s
[12:08:59.424] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:44:0)
[12:08:59.424] Timer tick, asking Buildbucket for the build status
[12:08:59.640] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:59.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:45:0) after 1m0s
[12:09:59.794] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:45:0)
[12:09:59.794] Timer tick, asking Buildbucket for the build status
[12:09:59.881] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:59.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:46:0) after 1m0s
[12:11:00.017] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:46:0)
[12:11:00.017] Timer tick, asking Buildbucket for the build status
[12:11:00.356] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:00.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:47:0) after 1m0s
[12:12:00.486] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:47:0)
[12:12:00.486] Timer tick, asking Buildbucket for the build status
[12:12:00.802] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:00.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:48:0) after 1m0s
[12:13:01.186] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:48:0)
[12:13:01.186] Timer tick, asking Buildbucket for the build status
[12:13:01.273] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:01.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:49:0) after 1m0s
[12:14:01.435] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:49:0)
[12:14:01.435] Timer tick, asking Buildbucket for the build status
[12:14:02.157] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:02.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:50:0) after 1m0s
[12:15:02.204] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:50:0)
[12:15:02.204] Timer tick, asking Buildbucket for the build status
[12:15:02.308] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:02.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:51:0) after 1m0s
[12:16:02.397] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:51:0)
[12:16:02.397] Timer tick, asking Buildbucket for the build status
[12:16:02.606] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:02.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:52:0) after 1m0s
[12:17:02.719] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:52:0)
[12:17:02.719] Timer tick, asking Buildbucket for the build status
[12:17:02.873] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:02.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:53:0) after 1m0s
[12:18:02.935] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:53:0)
[12:18:02.935] Timer tick, asking Buildbucket for the build status
[12:18:03.156] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:03.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:54:0) after 1m0s
[12:19:03.271] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:54:0)
[12:19:03.271] Timer tick, asking Buildbucket for the build status
[12:19:03.549] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:03.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:55:0) after 1m0s
[12:20:03.613] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:55:0)
[12:20:03.613] Timer tick, asking Buildbucket for the build status
[12:20:03.944] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:03.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:56:0) after 1m0s
[12:21:03.997] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:56:0)
[12:21:03.997] Timer tick, asking Buildbucket for the build status
[12:21:04.136] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:04.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:57:0) after 1m0s
[12:22:04.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:57:0)
[12:22:04.157] Timer tick, asking Buildbucket for the build status
[12:22:04.308] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:04.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:58:0) after 1m0s
[12:23:04.328] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:58:0)
[12:23:04.328] Timer tick, asking Buildbucket for the build status
[12:23:04.387] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:04.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:59:0) after 1m0s
[12:24:04.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:59:0)
[12:24:04.493] Timer tick, asking Buildbucket for the build status
[12:24:04.643] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:04.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:60:0) after 1m0s
[12:25:04.681] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:60:0)
[12:25:04.681] Timer tick, asking Buildbucket for the build status
[12:25:04.754] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:04.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:61:0) after 1m0s
[12:26:04.803] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:61:0)
[12:26:04.803] Timer tick, asking Buildbucket for the build status
[12:26:04.864] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:04.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:62:0) after 1m0s
[12:27:04.994] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:62:0)
[12:27:04.994] Timer tick, asking Buildbucket for the build status
[12:27:05.534] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:05.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:63:0) after 1m0s
[12:28:05.673] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:63:0)
[12:28:05.673] Timer tick, asking Buildbucket for the build status
[12:28:05.732] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:05.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:64:0) after 1m0s
[12:29:05.761] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:64:0)
[12:29:05.761] Timer tick, asking Buildbucket for the build status
[12:29:05.982] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:05.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:65:0) after 1m0s
[12:30:05.993] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:65:0)
[12:30:05.993] Timer tick, asking Buildbucket for the build status
[12:30:06.662] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:06.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:66:0) after 1m0s
[12:31:06.764] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:66:0)
[12:31:06.764] Timer tick, asking Buildbucket for the build status
[12:31:06.828] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:06.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:67:0) after 1m0s
[12:32:06.870] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:67:0)
[12:32:06.870] Timer tick, asking Buildbucket for the build status
[12:32:07.909] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:07.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:68:0) after 1m0s
[12:33:07.935] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:68:0)
[12:33:07.935] Timer tick, asking Buildbucket for the build status
[12:33:07.999] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:07.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:69:0) after 1m0s
[12:34:07.997] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:69:0)
[12:34:07.997] Timer tick, asking Buildbucket for the build status
[12:34:08.191] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:08.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:70:0) after 1m0s
[12:35:08.231] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:70:0)
[12:35:08.231] Timer tick, asking Buildbucket for the build status
[12:35:08.334] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:08.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:71:0) after 1m0s
[12:36:08.386] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:71:0)
[12:36:08.386] Timer tick, asking Buildbucket for the build status
[12:36:08.815] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:08.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:72:0) after 1m0s
[12:37:08.829] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:72:0)
[12:37:08.829] Timer tick, asking Buildbucket for the build status
[12:37:08.916] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:08.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:73:0) after 1m0s
[12:38:08.964] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:73:0)
[12:38:08.964] Timer tick, asking Buildbucket for the build status
[12:38:09.326] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:09.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:74:0) after 1m0s
[12:39:09.426] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:74:0)
[12:39:09.426] Timer tick, asking Buildbucket for the build status
[12:39:09.525] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:09.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:75:0) after 1m0s
[12:40:09.556] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:75:0)
[12:40:09.556] Timer tick, asking Buildbucket for the build status
[12:40:09.659] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:09.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:76:0) after 1m0s
[12:41:09.714] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:76:0)
[12:41:09.714] Timer tick, asking Buildbucket for the build status
[12:41:09.824] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:09.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:77:0) after 1m0s
[12:42:09.816] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:77:0)
[12:42:09.816] Timer tick, asking Buildbucket for the build status
[12:42:09.939] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:09.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:78:0) after 1m0s
[12:43:09.974] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:78:0)
[12:43:09.974] Timer tick, asking Buildbucket for the build status
[12:43:10.036] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:10.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:79:0) after 1m0s
[12:44:10.015] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:79:0)
[12:44:10.015] Timer tick, asking Buildbucket for the build status
[12:44:10.275] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:10.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:80:0) after 1m0s
[12:45:10.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:80:0)
[12:45:10.281] Timer tick, asking Buildbucket for the build status
[12:45:10.389] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:10.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:81:0) after 1m0s
[12:46:10.440] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:81:0)
[12:46:10.440] Timer tick, asking Buildbucket for the build status
[12:46:10.932] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:10.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:82:0) after 1m0s
[12:47:10.932] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:82:0)
[12:47:10.932] Timer tick, asking Buildbucket for the build status
[12:47:11.056] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:11.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:83:0) after 1m0s
[12:48:11.084] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:83:0)
[12:48:11.084] Timer tick, asking Buildbucket for the build status
[12:48:11.148] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:11.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:84:0) after 1m0s
[12:49:11.231] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:84:0)
[12:49:11.231] Timer tick, asking Buildbucket for the build status
[12:49:11.307] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:11.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:85:0) after 1m0s
[12:50:11.334] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:85:0)
[12:50:11.334] Timer tick, asking Buildbucket for the build status
[12:50:11.404] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:11.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:86:0) after 1m0s
[12:51:11.421] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:86:0)
[12:51:11.421] Timer tick, asking Buildbucket for the build status
[12:51:11.511] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:11.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:87:0) after 1m0s
[12:52:11.849] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:87:0)
[12:52:11.849] Timer tick, asking Buildbucket for the build status
[12:52:11.977] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:11.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:88:0) after 1m0s
[12:53:12.235] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:88:0)
[12:53:12.235] Timer tick, asking Buildbucket for the build status
[12:53:12.294] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:12.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:89:0) after 1m0s
[12:54:12.456] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:89:0)
[12:54:12.457] Timer tick, asking Buildbucket for the build status
[12:54:12.531] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:12.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:90:0) after 1m0s
[12:55:12.678] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:90:0)
[12:55:12.678] Timer tick, asking Buildbucket for the build status
[12:55:12.897] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:12.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:91:0) after 1m0s
[12:56:13.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:91:0)
[12:56:13.123] Timer tick, asking Buildbucket for the build status
[12:56:13.197] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:13.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:92:0) after 1m0s
[12:57:13.259] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:92:0)
[12:57:13.259] Timer tick, asking Buildbucket for the build status
[12:57:13.366] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:13.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:93:0) after 1m0s
[12:58:13.520] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:93:0)
[12:58:13.520] Timer tick, asking Buildbucket for the build status
[12:58:13.605] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:13.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:94:0) after 1m0s
[12:59:13.726] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:94:0)
[12:59:13.726] Timer tick, asking Buildbucket for the build status
[12:59:13.816] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:13.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:95:0) after 1m0s
[13:00:13.793] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:95:0)
[13:00:13.793] Timer tick, asking Buildbucket for the build status
[13:00:13.859] Build 8856501710069382992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:13.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9021931772310256528:96:0) after 1m0s
[13:01:00.406] Received PubSub notification, asking Buildbucket for the build status
[13:01:00.473] Build 8856501710069382992: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:01:00.473] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"completed_ts": "1612184459933430",
"created_by": "project:nacl",
"created_ts": "1612178808106524",
"failure_reason": "BUILD_FAILURE",
"id": "8856501710069382992",
"parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/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://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"vm78-m1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl\", \"buildername\": \"linux-64-validator-opt\", \"buildnumber\": 159, \"got_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_08d95548a5b2763894be45da75746e0a26ecb57e0e5972c9d07f9ce07d542665_v2\", \"builder_104bd9a76c554f27456c85a5491ddf5f85dcadb4b39968a4576655287a5cf526_v2\", \"builder_10cad3ca224a3c3cd7b7ab0b8c6a71270b500a4c9540c3fd71bf5a4ed7745605_v2\", \"builder_128648638a52124010bb106fd411e34109051b8aec58e7e96a1023cfb5053cb5_v2\", \"builder_15cfdcbddd47f31933ea7bcf8631d84c342cdb6dc95dfa5a159b8a19b9d950f3_v2\", \"builder_1d469ce73f696de6efd5e95df00b1ffe01dc80ee8f70f8b5f0d804841ba527c2_v2\", \"builder_1e6d0abe69a68a2aa6dacdd88a423d501535e2ffcca7b599f74b31063cfa633e_v2\", \"builder_22d9e6aabbb2e1244e03b5811e5a24e95da15b23ba7014a6eaf22f4085bde82d_v2\", \"builder_37eb74d66992cb39be8b12552d5432a77d4ecaec9cb7056c37174f508e325337_v2\", \"builder_3de9efdfb07bec08c314b1421feb6f4951c803d8a50db78576f9fb6390e65055_v2\", \"builder_41bff59e48143683eee7e209c35176da7d4c8f0bf6fc4cc531114b3142934c50_v2\", \"builder_4235cbca53d69e2265b4766f5a8bce926c6fc26ba44440d27213d5484c3aef8d_v2\", \"builder_449c2510a534bee5c7295f1727bde9860af936880631c6c900ad7524c472f417_v2\", \"builder_48eb80e70eb7f6723a7a20461f48a32835a1df27cc727380ebb75ed152255036_v2\", \"builder_4c879b492ad4ee1f5d8245dfd05718fa204c14b1ae0accb81fd1e5b1e33f4700_v2\", \"builder_510fe9a959fb6845ae4ece89b65dac460a600e94b8c166b9bb45da605730688a_v2\", \"builder_55dc93afa9a4c1762b1d33c20b1b59cfd69594e0eaf511fb311f3ae49187bb66_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_61fc32c3bc5a7ee2a26f46d61db55ba1f83c918285cc8d2dc6086b96201d8c90_v2\", \"builder_6510fbaae94dde04788e02d17e73669e56835958348ca880a036ad091a830fb5_v2\", \"builder_71ef69d0114959f59330b4253e21b16bb98a7f5d4c573a6f3e7120b7b72ed97a_v2\", \"builder_78893cbd4c292ba203eb7a045a56252df7f14bd0ad3b7b91fac8bfee09f12c2b_v2\", \"builder_794b511a2ab52049fdf1875de63519e62c2714c9a35cd7f139c68249331a49d1_v2\", \"builder_7ea55abfea25d314356d803a5f0ad62170c3dfd6004ffb0ffd94b75873d1ce3b_v2\", \"builder_868e37e72a67c9e0062fe47d1f774c83ff68e6ed82c10475786eedb84b9e23f8_v2\", \"builder_96b937d0444ca0237e7399236bdd6728df75ff624b2c87b99974683f32fc3980_v2\", \"builder_a00b1023121ea85e703f19c9cb4a0ba6469d60176e935a395f443f8a2c5759bb_v2\", \"builder_a0644330fbd5fcc319e71650781113ccfe591e85172c7efdd4f375c5b7e5d777_v2\", \"builder_a09264ab5ee361cfbd3c6cbd9a947de48f12b06fe92b2f02cd9f989a2ddee582_v2\", \"builder_a20b1ade66d3cd92c501ea59e0fa76fddd60e1587b9432210d1936953c00db76_v2\", \"builder_a25b9d306d94020ae7296883188cf45973114e65e21ed270d2dac1a6d2a1a00b_v2\", \"builder_a9e4216768aed9f485123609565fd359815e448a93032bcc2c2f129050314b0b_v2\", \"builder_ade509247dd25059f1eaa141a5cb597841f876685ca9aeb8535a3c7aae6fce13_v2\", \"builder_c4e0d2919c9449e57616d926859a612456f61676d96fb8625e0b44c6d892c145_v2\", \"builder_d6e34fbf0b97f42009d0e08c4c2190eabfc3f965b1a56dffd678d628cf424e8a_v2\", \"builder_e0c0963c08c3d1b26b4f237fcfd17b3c9492fb5a1f9625c008dc75e35d1233ce_v2\", \"builder_e532bcdd57ded30329b523a80650fc9919762d52882c48730e8df054fe74357c_v2\", \"builder_ef4359b44e8e6f795ffeafdf2e7f742ba75a6a61dcb0f4f425c29b4ccf5d74d9_v2\", \"builder_f03fc972fd74f67bd2e15a94ea28a57ee7e7ea7c6fd21129bd85bac28e06088b_v2\", \"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"builder_f685126061b3b63fc349ffa1b5e53cf0d3cd3c1ef180ecb8e5b4299d10c61666_v2\", \"builder_f9b91dfa880ce76d5543d89293005da4da7c590385ac28b54c79292c4bbfb1e1_v2\", \"builder_faaedc22e3ac6fec098652e7f0fd10d4f879f8f9ad55ea8ef979585009ee36da_v2\", \"git\", \"gocache\", \"goma_v2\", \"gopath\", \"infra_gclient_with_go\", \"vpython\", \"win_toolchain\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-avx2\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm78-m1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.ISO8859-1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.3\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m1\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612178854328212",
"status": "COMPLETED",
"status_changed_ts": "1612178855020944",
"tags": [
"build_address:luci.nacl.ci/linux-64-validator-opt/159",
"builder:linux-64-validator-opt",
"buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021931772310256528",
"scheduler_job_id:nacl/linux-64-validator-opt",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856501710069382992/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:517629ebfd0bdc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612184460015127",
"url": "https://ci.chromium.org/b/8856501710069382992",
"utcnow_ts": "1612184460463179"
}
}
[13:01:00.473] Invocation finished in 1h34m13.886776023s with status FAILED