[21:49:21.265] New invocation is queued and will start shortly
[21:49:22.849] Starting the invocation (attempt 1)
[21:49:22.908] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:22.909] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:22.909] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9084042122058593648",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64-tests-x86_64",
"scheduler_invocation_id:9084042122058593648",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"user_agent:luci-scheduler",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master"
]
}
[21:49:24.595] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "project:nacl",
"created_ts": "1552945763134056",
"id": "8918612059433901088",
"lease_expiration_ts": "1553096964077884",
"lease_key": "1392435801",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552945764351369",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5068",
"builder:linux-pnacl-x86_64-tests-x86_64",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084042122058593648",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8918612059433901088/+/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:43ab995d2a838f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552945764351465",
"url": "https://ci.chromium.org/b/8918612059433901088",
"utcnow_ts": "1552945764581001"
}
}
[21:49:24.595] Task URL: https://ci.chromium.org/b/8918612059433901088
[21:49:24.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:2:0) after 1m0s
[21:50:08.811] Received PubSub notification, asking Buildbucket for the build status
[21:50:09.097] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:24.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:2:0)
[21:50:24.601] Timer tick, asking Buildbucket for the build status
[21:50:24.653] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:24.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:4:0) after 1m0s
[21:51:24.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:4:0)
[21:51:24.636] Timer tick, asking Buildbucket for the build status
[21:51:24.725] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:24.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:5:0) after 1m0s
[21:52:24.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:5:0)
[21:52:24.942] Timer tick, asking Buildbucket for the build status
[21:52:25.022] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:25.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:6:0) after 1m0s
[21:53:25.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:6:0)
[21:53:25.013] Timer tick, asking Buildbucket for the build status
[21:53:25.140] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:25.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:7:0) after 1m0s
[21:54:25.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:7:0)
[21:54:25.207] Timer tick, asking Buildbucket for the build status
[21:54:25.291] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:25.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:8:0) after 1m0s
[21:55:25.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:8:0)
[21:55:25.269] Timer tick, asking Buildbucket for the build status
[21:55:25.452] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:9:0) after 1m0s
[21:56:25.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:9:0)
[21:56:25.654] Timer tick, asking Buildbucket for the build status
[21:56:25.746] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:25.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:10:0) after 1m0s
[21:57:25.765] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:10:0)
[21:57:25.765] Timer tick, asking Buildbucket for the build status
[21:57:25.865] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:25.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:11:0) after 1m0s
[21:58:25.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:11:0)
[21:58:25.869] Timer tick, asking Buildbucket for the build status
[21:58:25.909] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:25.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:12:0) after 1m0s
[21:59:25.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:12:0)
[21:59:25.941] Timer tick, asking Buildbucket for the build status
[21:59:25.987] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:25.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:13:0) after 1m0s
[22:00:26.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:13:0)
[22:00:26.057] Timer tick, asking Buildbucket for the build status
[22:00:26.205] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:26.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:14:0) after 1m0s
[22:01:26.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:14:0)
[22:01:26.262] Timer tick, asking Buildbucket for the build status
[22:01:26.306] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:26.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:15:0) after 1m0s
[22:02:26.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:15:0)
[22:02:26.327] Timer tick, asking Buildbucket for the build status
[22:02:26.551] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:26.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:16:0) after 1m0s
[22:03:26.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:16:0)
[22:03:26.530] Timer tick, asking Buildbucket for the build status
[22:03:26.572] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:26.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:17:0) after 1m0s
[22:04:26.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:17:0)
[22:04:26.638] Timer tick, asking Buildbucket for the build status
[22:04:27.002] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:27.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:18:0) after 1m0s
[22:05:27.066] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:18:0)
[22:05:27.066] Timer tick, asking Buildbucket for the build status
[22:05:27.170] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:27.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:19:0) after 1m0s
[22:06:27.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:19:0)
[22:06:27.191] Timer tick, asking Buildbucket for the build status
[22:06:27.375] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:27.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:20:0) after 1m0s
[22:07:27.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:20:0)
[22:07:27.378] Timer tick, asking Buildbucket for the build status
[22:07:27.538] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:27.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:21:0) after 1m0s
[22:08:27.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:21:0)
[22:08:27.563] Timer tick, asking Buildbucket for the build status
[22:08:27.683] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:27.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:22:0) after 1m0s
[22:09:27.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:22:0)
[22:09:27.793] Timer tick, asking Buildbucket for the build status
[22:09:27.841] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:27.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:23:0) after 1m0s
[22:10:27.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:23:0)
[22:10:27.868] Timer tick, asking Buildbucket for the build status
[22:10:27.987] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:27.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:24:0) after 1m0s
[22:11:28.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:24:0)
[22:11:28.008] Timer tick, asking Buildbucket for the build status
[22:11:28.212] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:28.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:25:0) after 1m0s
[22:12:28.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:25:0)
[22:12:28.219] Timer tick, asking Buildbucket for the build status
[22:12:28.571] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:28.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:26:0) after 1m0s
[22:13:28.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:26:0)
[22:13:28.561] Timer tick, asking Buildbucket for the build status
[22:13:28.672] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:28.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:27:0) after 1m0s
[22:14:28.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:27:0)
[22:14:28.704] Timer tick, asking Buildbucket for the build status
[22:14:28.791] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:28.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:28:0) after 1m0s
[22:15:28.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:28:0)
[22:15:28.781] Timer tick, asking Buildbucket for the build status
[22:15:28.865] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:28.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:29:0) after 1m0s
[22:16:28.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:29:0)
[22:16:28.843] Timer tick, asking Buildbucket for the build status
[22:16:28.961] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:28.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:30:0) after 1m0s
[22:17:28.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:30:0)
[22:17:28.983] Timer tick, asking Buildbucket for the build status
[22:17:29.171] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:29.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:31:0) after 1m0s
[22:18:29.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:31:0)
[22:18:29.195] Timer tick, asking Buildbucket for the build status
[22:18:29.281] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:29.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:32:0) after 1m0s
[22:19:29.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:32:0)
[22:19:29.256] Timer tick, asking Buildbucket for the build status
[22:19:29.406] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:33:0) after 1m0s
[22:20:29.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:33:0)
[22:20:29.415] Timer tick, asking Buildbucket for the build status
[22:20:29.549] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:34:0) after 1m0s
[22:21:29.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:34:0)
[22:21:29.597] Timer tick, asking Buildbucket for the build status
[22:21:29.732] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:29.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:35:0) after 1m0s
[22:22:29.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:35:0)
[22:22:29.801] Timer tick, asking Buildbucket for the build status
[22:22:29.834] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:29.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:36:0) after 1m0s
[22:23:29.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:36:0)
[22:23:29.864] Timer tick, asking Buildbucket for the build status
[22:23:29.983] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:29.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:37:0) after 1m0s
[22:24:29.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:37:0)
[22:24:29.994] Timer tick, asking Buildbucket for the build status
[22:24:30.071] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:30.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:38:0) after 1m0s
[22:25:30.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:38:0)
[22:25:30.120] Timer tick, asking Buildbucket for the build status
[22:25:30.163] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:30.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:39:0) after 1m0s
[22:26:30.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:39:0)
[22:26:30.185] Timer tick, asking Buildbucket for the build status
[22:26:30.266] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:30.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:40:0) after 1m0s
[22:27:30.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:40:0)
[22:27:30.306] Timer tick, asking Buildbucket for the build status
[22:27:30.348] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:30.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:41:0) after 1m0s
[22:28:30.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:41:0)
[22:28:30.374] Timer tick, asking Buildbucket for the build status
[22:28:30.492] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:30.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:42:0) after 1m0s
[22:29:30.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:42:0)
[22:29:30.479] Timer tick, asking Buildbucket for the build status
[22:29:30.523] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:30.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:43:0) after 1m0s
[22:30:30.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:43:0)
[22:30:30.536] Timer tick, asking Buildbucket for the build status
[22:30:30.600] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:30.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:44:0) after 1m0s
[22:31:30.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:44:0)
[22:31:30.585] Timer tick, asking Buildbucket for the build status
[22:31:30.632] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:30.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:45:0) after 1m0s
[22:32:30.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:45:0)
[22:32:30.642] Timer tick, asking Buildbucket for the build status
[22:32:30.773] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:30.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:46:0) after 1m0s
[22:33:30.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:46:0)
[22:33:30.772] Timer tick, asking Buildbucket for the build status
[22:33:30.884] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:30.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:47:0) after 1m0s
[22:34:30.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:47:0)
[22:34:30.871] Timer tick, asking Buildbucket for the build status
[22:34:30.911] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:30.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:48:0) after 1m0s
[22:35:30.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:48:0)
[22:35:30.889] Timer tick, asking Buildbucket for the build status
[22:35:30.951] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:30.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:49:0) after 1m0s
[22:36:30.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:49:0)
[22:36:30.968] Timer tick, asking Buildbucket for the build status
[22:36:31.023] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:31.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:50:0) after 1m0s
[22:37:31.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:50:0)
[22:37:31.073] Timer tick, asking Buildbucket for the build status
[22:37:31.123] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:31.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:51:0) after 1m0s
[22:38:31.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:51:0)
[22:38:31.118] Timer tick, asking Buildbucket for the build status
[22:38:31.181] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:31.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:52:0) after 1m0s
[22:39:31.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:52:0)
[22:39:31.221] Timer tick, asking Buildbucket for the build status
[22:39:31.273] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:31.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:53:0) after 1m0s
[22:40:31.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:53:0)
[22:40:31.294] Timer tick, asking Buildbucket for the build status
[22:40:31.332] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:31.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:54:0) after 1m0s
[22:41:31.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:54:0)
[22:41:31.394] Timer tick, asking Buildbucket for the build status
[22:41:31.440] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:31.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:55:0) after 1m0s
[22:42:31.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:55:0)
[22:42:31.418] Timer tick, asking Buildbucket for the build status
[22:42:31.459] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:31.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:56:0) after 1m0s
[22:43:31.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:56:0)
[22:43:31.497] Timer tick, asking Buildbucket for the build status
[22:43:31.554] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:31.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:57:0) after 1m0s
[22:44:31.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:57:0)
[22:44:31.543] Timer tick, asking Buildbucket for the build status
[22:44:31.680] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:31.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:58:0) after 1m0s
[22:45:31.693] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:58:0)
[22:45:31.693] Timer tick, asking Buildbucket for the build status
[22:45:31.780] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:31.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:59:0) after 1m0s
[22:46:31.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:59:0)
[22:46:31.769] Timer tick, asking Buildbucket for the build status
[22:46:31.820] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:31.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:60:0) after 1m0s
[22:47:31.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:60:0)
[22:47:31.825] Timer tick, asking Buildbucket for the build status
[22:47:32.863] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:32.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:61:0) after 1m0s
[22:48:32.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:61:0)
[22:48:32.885] Timer tick, asking Buildbucket for the build status
[22:48:32.992] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:32.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:62:0) after 1m0s
[22:49:33.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:62:0)
[22:49:33.016] Timer tick, asking Buildbucket for the build status
[22:49:33.057] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:33.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:63:0) after 1m0s
[22:50:33.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:63:0)
[22:50:33.076] Timer tick, asking Buildbucket for the build status
[22:50:33.126] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:33.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:64:0) after 1m0s
[22:51:33.144] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:64:0)
[22:51:33.144] Timer tick, asking Buildbucket for the build status
[22:51:33.213] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:33.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:65:0) after 1m0s
[22:52:33.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:65:0)
[22:52:33.269] Timer tick, asking Buildbucket for the build status
[22:52:33.329] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:33.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:66:0) after 1m0s
[22:53:33.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:66:0)
[22:53:33.303] Timer tick, asking Buildbucket for the build status
[22:53:33.345] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:33.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:67:0) after 1m0s
[22:54:33.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:67:0)
[22:54:33.389] Timer tick, asking Buildbucket for the build status
[22:54:33.431] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:33.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:68:0) after 1m0s
[22:55:33.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:68:0)
[22:55:33.410] Timer tick, asking Buildbucket for the build status
[22:55:33.516] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:33.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:69:0) after 1m0s
[22:56:33.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:69:0)
[22:56:33.540] Timer tick, asking Buildbucket for the build status
[22:56:33.607] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:33.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:70:0) after 1m0s
[22:57:33.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:70:0)
[22:57:33.626] Timer tick, asking Buildbucket for the build status
[22:57:33.684] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:33.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:71:0) after 1m0s
[22:58:33.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:71:0)
[22:58:33.697] Timer tick, asking Buildbucket for the build status
[22:58:33.864] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:33.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:72:0) after 1m0s
[22:59:33.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:72:0)
[22:59:33.885] Timer tick, asking Buildbucket for the build status
[22:59:33.928] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:33.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:73:0) after 1m0s
[23:00:33.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:73:0)
[23:00:33.949] Timer tick, asking Buildbucket for the build status
[23:00:34.001] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:34.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:74:0) after 1m0s
[23:01:34.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:74:0)
[23:01:34.051] Timer tick, asking Buildbucket for the build status
[23:01:34.269] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:34.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:75:0) after 1m0s
[23:02:34.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:75:0)
[23:02:34.292] Timer tick, asking Buildbucket for the build status
[23:02:34.349] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:34.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:76:0) after 1m0s
[23:03:34.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:76:0)
[23:03:34.379] Timer tick, asking Buildbucket for the build status
[23:03:34.428] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:34.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:77:0) after 1m0s
[23:04:34.422] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:77:0)
[23:04:34.422] Timer tick, asking Buildbucket for the build status
[23:04:34.479] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:34.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:78:0) after 1m0s
[23:05:34.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:78:0)
[23:05:34.452] Timer tick, asking Buildbucket for the build status
[23:05:34.581] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:34.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:79:0) after 1m0s
[23:06:34.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:79:0)
[23:06:34.559] Timer tick, asking Buildbucket for the build status
[23:06:34.620] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:34.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:80:0) after 1m0s
[23:07:34.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:80:0)
[23:07:34.636] Timer tick, asking Buildbucket for the build status
[23:07:34.712] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:34.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:81:0) after 1m0s
[23:08:34.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:81:0)
[23:08:34.781] Timer tick, asking Buildbucket for the build status
[23:08:35.021] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:35.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:82:0) after 1m0s
[23:09:35.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:82:0)
[23:09:35.012] Timer tick, asking Buildbucket for the build status
[23:09:35.054] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:35.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:83:0) after 1m0s
[23:10:35.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:83:0)
[23:10:35.082] Timer tick, asking Buildbucket for the build status
[23:10:35.308] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:35.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:84:0) after 1m0s
[23:11:35.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:84:0)
[23:11:35.319] Timer tick, asking Buildbucket for the build status
[23:11:35.611] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:35.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:85:0) after 1m0s
[23:12:35.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:85:0)
[23:12:35.625] Timer tick, asking Buildbucket for the build status
[23:12:35.668] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:86:0) after 1m0s
[23:13:35.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:86:0)
[23:13:35.692] Timer tick, asking Buildbucket for the build status
[23:13:35.736] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:35.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:87:0) after 1m0s
[23:14:35.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:87:0)
[23:14:35.729] Timer tick, asking Buildbucket for the build status
[23:14:35.796] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:35.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:88:0) after 1m0s
[23:15:35.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:88:0)
[23:15:35.818] Timer tick, asking Buildbucket for the build status
[23:15:35.869] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:35.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:89:0) after 1m0s
[23:16:35.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:89:0)
[23:16:35.854] Timer tick, asking Buildbucket for the build status
[23:16:36.094] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:90:0) after 1m0s
[23:17:36.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:90:0)
[23:17:36.083] Timer tick, asking Buildbucket for the build status
[23:17:36.148] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:91:0) after 1m0s
[23:18:36.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:91:0)
[23:18:36.125] Timer tick, asking Buildbucket for the build status
[23:18:36.180] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:92:0) after 1m0s
[23:19:36.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:92:0)
[23:19:36.217] Timer tick, asking Buildbucket for the build status
[23:19:36.284] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:93:0) after 1m0s
[23:20:36.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:93:0)
[23:20:36.269] Timer tick, asking Buildbucket for the build status
[23:20:36.314] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:94:0) after 1m0s
[23:21:36.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:94:0)
[23:21:36.287] Timer tick, asking Buildbucket for the build status
[23:21:36.369] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:36.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:95:0) after 1m0s
[23:22:36.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:95:0)
[23:22:36.390] Timer tick, asking Buildbucket for the build status
[23:22:36.440] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:36.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:96:0) after 1m0s
[23:23:36.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:96:0)
[23:23:36.468] Timer tick, asking Buildbucket for the build status
[23:23:36.511] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:36.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:97:0) after 1m0s
[23:24:37.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:97:0)
[23:24:37.841] Timer tick, asking Buildbucket for the build status
[23:24:37.888] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:37.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:98:0) after 1m0s
[23:25:37.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:98:0)
[23:25:37.902] Timer tick, asking Buildbucket for the build status
[23:25:38.075] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:38.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:99:0) after 1m0s
[23:26:38.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:99:0)
[23:26:38.099] Timer tick, asking Buildbucket for the build status
[23:26:38.181] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:38.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:100:0) after 1m0s
[23:27:38.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:100:0)
[23:27:38.168] Timer tick, asking Buildbucket for the build status
[23:27:38.266] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:38.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:101:0) after 1m0s
[23:28:38.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:101:0)
[23:28:38.292] Timer tick, asking Buildbucket for the build status
[23:28:38.499] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:38.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:102:0) after 1m0s
[23:29:38.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:102:0)
[23:29:38.494] Timer tick, asking Buildbucket for the build status
[23:29:38.528] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:38.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:103:0) after 1m0s
[23:30:38.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:103:0)
[23:30:38.523] Timer tick, asking Buildbucket for the build status
[23:30:38.575] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:38.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:104:0) after 1m0s
[23:31:38.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:104:0)
[23:31:38.605] Timer tick, asking Buildbucket for the build status
[23:31:38.649] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:105:0) after 1m0s
[23:32:38.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:105:0)
[23:32:38.683] Timer tick, asking Buildbucket for the build status
[23:32:38.764] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:106:0) after 1m0s
[23:33:38.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:106:0)
[23:33:38.756] Timer tick, asking Buildbucket for the build status
[23:33:38.926] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:107:0) after 1m0s
[23:34:38.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:107:0)
[23:34:38.948] Timer tick, asking Buildbucket for the build status
[23:34:39.103] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:39.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:108:0) after 1m0s
[23:35:39.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:108:0)
[23:35:39.077] Timer tick, asking Buildbucket for the build status
[23:35:39.183] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:39.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:109:0) after 1m0s
[23:36:39.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:109:0)
[23:36:39.203] Timer tick, asking Buildbucket for the build status
[23:36:39.376] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:39.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:110:0) after 1m0s
[23:37:39.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:110:0)
[23:37:39.403] Timer tick, asking Buildbucket for the build status
[23:37:39.441] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:39.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:111:0) after 1m0s
[23:38:39.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:111:0)
[23:38:39.456] Timer tick, asking Buildbucket for the build status
[23:38:39.561] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:39.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:112:0) after 1m0s
[23:39:39.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:112:0)
[23:39:39.595] Timer tick, asking Buildbucket for the build status
[23:39:39.667] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:39.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:113:0) after 1m0s
[23:40:39.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:113:0)
[23:40:39.700] Timer tick, asking Buildbucket for the build status
[23:40:39.748] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:39.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:114:0) after 1m0s
[23:41:39.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:114:0)
[23:41:39.767] Timer tick, asking Buildbucket for the build status
[23:41:39.832] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:115:0) after 1m0s
[23:42:39.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:115:0)
[23:42:39.856] Timer tick, asking Buildbucket for the build status
[23:42:39.899] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:116:0) after 1m0s
[23:43:39.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:116:0)
[23:43:39.967] Timer tick, asking Buildbucket for the build status
[23:43:40.021] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:40.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:117:0) after 1m0s
[23:44:40.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:117:0)
[23:44:40.041] Timer tick, asking Buildbucket for the build status
[23:44:40.144] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:40.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:118:0) after 1m0s
[23:45:40.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:118:0)
[23:45:40.214] Timer tick, asking Buildbucket for the build status
[23:45:40.274] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:40.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:119:0) after 1m0s
[23:46:40.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:119:0)
[23:46:40.295] Timer tick, asking Buildbucket for the build status
[23:46:40.469] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:40.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:120:0) after 1m0s
[23:47:40.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:120:0)
[23:47:40.479] Timer tick, asking Buildbucket for the build status
[23:47:40.672] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:40.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:121:0) after 1m0s
[23:48:40.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:121:0)
[23:48:40.642] Timer tick, asking Buildbucket for the build status
[23:48:40.690] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:122:0) after 1m0s
[23:49:40.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:122:0)
[23:49:40.719] Timer tick, asking Buildbucket for the build status
[23:49:40.961] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:123:0) after 1m0s
[23:50:40.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:123:0)
[23:50:40.959] Timer tick, asking Buildbucket for the build status
[23:50:40.994] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:124:0) after 1m0s
[23:51:40.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:124:0)
[23:51:40.992] Timer tick, asking Buildbucket for the build status
[23:51:41.093] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:41.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:125:0) after 1m0s
[23:52:41.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:125:0)
[23:52:41.112] Timer tick, asking Buildbucket for the build status
[23:52:41.164] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:41.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:126:0) after 1m0s
[23:53:41.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:126:0)
[23:53:41.180] Timer tick, asking Buildbucket for the build status
[23:53:41.225] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:41.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:127:0) after 1m0s
[23:54:41.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:127:0)
[23:54:41.249] Timer tick, asking Buildbucket for the build status
[23:54:41.313] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:128:0) after 1m0s
[23:55:41.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:128:0)
[23:55:41.338] Timer tick, asking Buildbucket for the build status
[23:55:41.420] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:41.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:129:0) after 1m0s
[23:56:41.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:129:0)
[23:56:41.442] Timer tick, asking Buildbucket for the build status
[23:56:41.690] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:41.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:130:0) after 1m0s
[23:57:41.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:130:0)
[23:57:41.690] Timer tick, asking Buildbucket for the build status
[23:57:41.808] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:41.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:131:0) after 1m0s
[23:58:41.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:131:0)
[23:58:41.809] Timer tick, asking Buildbucket for the build status
[23:58:41.900] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:41.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:132:0) after 1m0s
[23:59:41.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:132:0)
[23:59:41.903] Timer tick, asking Buildbucket for the build status
[23:59:42.001] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:133:0) after 1m0s
[00:00:42.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:133:0)
[00:00:42.026] Timer tick, asking Buildbucket for the build status
[00:00:42.071] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:42.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:134:0) after 1m0s
[00:01:42.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:134:0)
[00:01:42.071] Timer tick, asking Buildbucket for the build status
[00:01:42.303] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:42.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:135:0) after 1m0s
[00:02:42.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:135:0)
[00:02:42.327] Timer tick, asking Buildbucket for the build status
[00:02:42.530] Build 8918612059433901088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:42.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9084042122058593648:136:0) after 1m0s
[00:02:51.286] Received PubSub notification, asking Buildbucket for the build status
[00:02:51.404] Build 8918612059433901088: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:02:51.404] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1552953769918557",
"created_by": "project:nacl",
"created_ts": "1552945763134056",
"failure_reason": "BUILD_FAILURE",
"id": "8918612059433901088",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-p02c\"], \"pool\": [\"luci.nacl.toolchain\"]}}, \"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b_sz\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 5068, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"got_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-p02c\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552945807982932",
"status": "COMPLETED",
"status_changed_ts": "1552953771012786",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5068",
"builder:linux-pnacl-x86_64-tests-x86_64",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084042122058593648",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8918612059433901088/+/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:43ab995d2a838f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552953771018501",
"url": "https://ci.chromium.org/b/8918612059433901088",
"utcnow_ts": "1552953771386759"
}
}
[00:02:51.404] Invocation finished in 2h13m30.155393314s with status FAILED