[21:26:00.384] New invocation is queued and will start shortly
[21:26:03.711] Starting the invocation (attempt 1)
[21:26:03.784] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:26:03.784] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:26:03.784] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9052697040620470976",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"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\":\"21be4b46c82c14625859b4929af9ef6433fa04a5\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@21be4b46c82c14625859b4929af9ef6433fa04a5\",\"title\":\"21be4b46c82c14625859b4929af9ef6433fa04a5\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/21be4b46c82c14625859b4929af9ef6433fa04a5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"21be4b46c82c14625859b4929af9ef6433fa04a5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64-tests-x86_32",
"scheduler_invocation_id:9052697040620470976",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"user_agent:luci-scheduler",
"buildset:commit/git/21be4b46c82c14625859b4929af9ef6433fa04a5",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/21be4b46c82c14625859b4929af9ef6433fa04a5",
"gitiles_ref:refs/heads/master"
]
}
[21:26:04.765] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1582838763900185",
"id": "8887266976262905008",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@21be4b46c82c14625859b4929af9ef6433fa04a5\", \"title\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/21be4b46c82c14625859b4929af9ef6433fa04a5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582838764554086",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5140",
"builder:linux-pnacl-x86_64-tests-x86_32",
"buildset:commit/git/21be4b46c82c14625859b4929af9ef6433fa04a5",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/21be4b46c82c14625859b4929af9ef6433fa04a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052697040620470976",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8887266976262905008/+/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": "1582838764554229",
"url": "https://ci.chromium.org/b/8887266976262905008",
"utcnow_ts": "1582838764747272"
}
}
[21:26:04.765] Task URL: https://ci.chromium.org/b/8887266976262905008
[21:26:04.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:2:0) after 1m0s
[21:27:04.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:2:0)
[21:27:04.844] Timer tick, asking Buildbucket for the build status
[21:27:04.934] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:04.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:3:0) after 1m0s
[21:28:05.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:3:0)
[21:28:05.154] Timer tick, asking Buildbucket for the build status
[21:28:05.291] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:05.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:4:0) after 1m0s
[21:29:05.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:4:0)
[21:29:05.505] Timer tick, asking Buildbucket for the build status
[21:29:05.636] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:05.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:5:0) after 1m0s
[21:30:05.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:5:0)
[21:30:05.789] Timer tick, asking Buildbucket for the build status
[21:30:05.951] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:05.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:6:0) after 1m0s
[21:31:06.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:6:0)
[21:31:06.429] Timer tick, asking Buildbucket for the build status
[21:31:06.689] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:06.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:7:0) after 1m0s
[21:32:06.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:7:0)
[21:32:06.784] Timer tick, asking Buildbucket for the build status
[21:32:06.942] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:06.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:8:0) after 1m0s
[21:33:07.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:8:0)
[21:33:07.021] Timer tick, asking Buildbucket for the build status
[21:33:07.261] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:07.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:9:0) after 1m0s
[21:34:07.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:9:0)
[21:34:07.368] Timer tick, asking Buildbucket for the build status
[21:34:07.480] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:07.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:10:0) after 1m0s
[21:35:07.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:10:0)
[21:35:07.648] Timer tick, asking Buildbucket for the build status
[21:35:07.775] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:07.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:11:0) after 1m0s
[21:36:07.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:11:0)
[21:36:07.935] Timer tick, asking Buildbucket for the build status
[21:36:10.924] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:10.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:12:0) after 1m0s
[21:37:10.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:12:0)
[21:37:10.982] Timer tick, asking Buildbucket for the build status
[21:37:11.131] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:11.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:13:0) after 1m0s
[21:38:11.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:13:0)
[21:38:11.220] Timer tick, asking Buildbucket for the build status
[21:38:11.368] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:11.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:14:0) after 1m0s
[21:39:11.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:14:0)
[21:39:11.529] Timer tick, asking Buildbucket for the build status
[21:39:11.623] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:11.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:15:0) after 1m0s
[21:40:11.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:15:0)
[21:40:11.648] Timer tick, asking Buildbucket for the build status
[21:40:11.871] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:11.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:16:0) after 1m0s
[21:41:11.961] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:16:0)
[21:41:11.961] Timer tick, asking Buildbucket for the build status
[21:41:12.450] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:12.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:17:0) after 1m0s
[21:42:12.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:17:0)
[21:42:12.475] Timer tick, asking Buildbucket for the build status
[21:42:13.387] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:13.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:18:0) after 1m0s
[21:43:13.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:18:0)
[21:43:13.447] Timer tick, asking Buildbucket for the build status
[21:43:14.298] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:14.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:19:0) after 1m0s
[21:44:14.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:19:0)
[21:44:14.460] Timer tick, asking Buildbucket for the build status
[21:44:14.573] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:14.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:20:0) after 1m0s
[21:45:14.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:20:0)
[21:45:14.633] Timer tick, asking Buildbucket for the build status
[21:45:14.727] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:14.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:21:0) after 1m0s
[21:46:14.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:21:0)
[21:46:14.776] Timer tick, asking Buildbucket for the build status
[21:46:14.891] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:14.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:22:0) after 1m0s
[21:47:14.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:22:0)
[21:47:14.926] Timer tick, asking Buildbucket for the build status
[21:47:15.110] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:15.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:23:0) after 1m0s
[21:48:15.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:23:0)
[21:48:15.117] Timer tick, asking Buildbucket for the build status
[21:48:15.371] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:15.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:24:0) after 1m0s
[21:49:15.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:24:0)
[21:49:15.397] Timer tick, asking Buildbucket for the build status
[21:49:15.552] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:15.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:25:0) after 1m0s
[21:50:15.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:25:0)
[21:50:15.582] Timer tick, asking Buildbucket for the build status
[21:50:16.032] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:16.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:26:0) after 1m0s
[21:51:16.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:26:0)
[21:51:16.054] Timer tick, asking Buildbucket for the build status
[21:51:16.359] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:16.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:27:0) after 1m0s
[21:52:16.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:27:0)
[21:52:16.396] Timer tick, asking Buildbucket for the build status
[21:52:16.828] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:16.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:28:0) after 1m0s
[21:53:16.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:28:0)
[21:53:16.877] Timer tick, asking Buildbucket for the build status
[21:53:16.977] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:16.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:29:0) after 1m0s
[21:54:17.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:29:0)
[21:54:17.049] Timer tick, asking Buildbucket for the build status
[21:54:17.377] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:17.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:30:0) after 1m0s
[21:55:17.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:30:0)
[21:55:17.443] Timer tick, asking Buildbucket for the build status
[21:55:17.568] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:17.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:31:0) after 1m0s
[21:56:17.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:31:0)
[21:56:17.606] Timer tick, asking Buildbucket for the build status
[21:56:18.208] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:18.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:32:0) after 1m0s
[21:57:18.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:32:0)
[21:57:18.295] Timer tick, asking Buildbucket for the build status
[21:57:18.541] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:18.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:33:0) after 1m0s
[21:58:18.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:33:0)
[21:58:18.565] Timer tick, asking Buildbucket for the build status
[21:58:18.943] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:18.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:34:0) after 1m0s
[21:59:18.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:34:0)
[21:59:18.933] Timer tick, asking Buildbucket for the build status
[21:59:19.028] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:19.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:35:0) after 1m0s
[22:00:19.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:35:0)
[22:00:19.086] Timer tick, asking Buildbucket for the build status
[22:00:19.276] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:19.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:36:0) after 1m0s
[22:01:19.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:36:0)
[22:01:19.357] Timer tick, asking Buildbucket for the build status
[22:01:19.464] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:19.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:37:0) after 1m0s
[22:02:19.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:37:0)
[22:02:19.494] Timer tick, asking Buildbucket for the build status
[22:02:19.746] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:19.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:38:0) after 1m0s
[22:03:19.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:38:0)
[22:03:19.817] Timer tick, asking Buildbucket for the build status
[22:03:20.587] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:20.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:39:0) after 1m0s
[22:04:20.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:39:0)
[22:04:20.769] Timer tick, asking Buildbucket for the build status
[22:04:21.263] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:21.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:40:0) after 1m0s
[22:05:21.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:40:0)
[22:05:21.378] Timer tick, asking Buildbucket for the build status
[22:05:21.789] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:21.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:41:0) after 1m0s
[22:06:22.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:41:0)
[22:06:22.073] Timer tick, asking Buildbucket for the build status
[22:06:22.186] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:22.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:42:0) after 1m0s
[22:07:22.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:42:0)
[22:07:22.225] Timer tick, asking Buildbucket for the build status
[22:07:22.356] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:22.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:43:0) after 1m0s
[22:08:22.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:43:0)
[22:08:22.404] Timer tick, asking Buildbucket for the build status
[22:08:22.514] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:22.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:44:0) after 1m0s
[22:09:22.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:44:0)
[22:09:22.596] Timer tick, asking Buildbucket for the build status
[22:09:23.091] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:23.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:45:0) after 1m0s
[22:10:23.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:45:0)
[22:10:23.192] Timer tick, asking Buildbucket for the build status
[22:10:23.332] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:23.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:46:0) after 1m0s
[22:11:23.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:46:0)
[22:11:23.432] Timer tick, asking Buildbucket for the build status
[22:11:23.604] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:23.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:47:0) after 1m0s
[22:12:23.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:47:0)
[22:12:23.635] Timer tick, asking Buildbucket for the build status
[22:12:23.987] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:23.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:48:0) after 1m0s
[22:13:24.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:48:0)
[22:13:24.077] Timer tick, asking Buildbucket for the build status
[22:13:24.252] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:24.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:49:0) after 1m0s
[22:14:24.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:49:0)
[22:14:24.297] Timer tick, asking Buildbucket for the build status
[22:14:24.488] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:24.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:50:0) after 1m0s
[22:15:24.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:50:0)
[22:15:24.560] Timer tick, asking Buildbucket for the build status
[22:15:24.833] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:24.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:51:0) after 1m0s
[22:16:24.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:51:0)
[22:16:24.928] Timer tick, asking Buildbucket for the build status
[22:16:25.064] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:25.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:52:0) after 1m0s
[22:17:25.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:52:0)
[22:17:25.099] Timer tick, asking Buildbucket for the build status
[22:17:25.217] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:25.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:53:0) after 1m0s
[22:18:25.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:53:0)
[22:18:25.364] Timer tick, asking Buildbucket for the build status
[22:18:25.469] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:25.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:54:0) after 1m0s
[22:19:25.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:54:0)
[22:19:25.515] Timer tick, asking Buildbucket for the build status
[22:19:25.607] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:25.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:55:0) after 1m0s
[22:20:25.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:55:0)
[22:20:25.727] Timer tick, asking Buildbucket for the build status
[22:20:25.965] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:25.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:56:0) after 1m0s
[22:21:26.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:56:0)
[22:21:26.024] Timer tick, asking Buildbucket for the build status
[22:21:26.132] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:26.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:57:0) after 1m0s
[22:22:26.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:57:0)
[22:22:26.211] Timer tick, asking Buildbucket for the build status
[22:22:26.347] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:26.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:58:0) after 1m0s
[22:23:26.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:58:0)
[22:23:26.380] Timer tick, asking Buildbucket for the build status
[22:23:26.497] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:26.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:59:0) after 1m0s
[22:24:26.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:59:0)
[22:24:26.625] Timer tick, asking Buildbucket for the build status
[22:24:26.738] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:26.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:60:0) after 1m0s
[22:25:26.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:60:0)
[22:25:26.808] Timer tick, asking Buildbucket for the build status
[22:25:26.974] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:26.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:61:0) after 1m0s
[22:26:27.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:61:0)
[22:26:27.094] Timer tick, asking Buildbucket for the build status
[22:26:27.227] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:27.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:62:0) after 1m0s
[22:27:27.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:62:0)
[22:27:27.423] Timer tick, asking Buildbucket for the build status
[22:27:27.580] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:27.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:63:0) after 1m0s
[22:28:27.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:63:0)
[22:28:27.618] Timer tick, asking Buildbucket for the build status
[22:28:27.800] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:27.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:64:0) after 1m0s
[22:29:27.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:64:0)
[22:29:27.844] Timer tick, asking Buildbucket for the build status
[22:29:27.940] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:27.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:65:0) after 1m0s
[22:30:27.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:65:0)
[22:30:27.963] Timer tick, asking Buildbucket for the build status
[22:30:28.076] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:28.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:66:0) after 1m0s
[22:31:28.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:66:0)
[22:31:28.202] Timer tick, asking Buildbucket for the build status
[22:31:28.382] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:28.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:67:0) after 1m0s
[22:32:28.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:67:0)
[22:32:28.486] Timer tick, asking Buildbucket for the build status
[22:32:28.629] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:28.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:68:0) after 1m0s
[22:33:28.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:68:0)
[22:33:28.735] Timer tick, asking Buildbucket for the build status
[22:33:28.906] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:28.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:69:0) after 1m0s
[22:34:29.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:69:0)
[22:34:29.032] Timer tick, asking Buildbucket for the build status
[22:34:29.183] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:29.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:70:0) after 1m0s
[22:35:29.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:70:0)
[22:35:29.186] Timer tick, asking Buildbucket for the build status
[22:35:29.317] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:29.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:71:0) after 1m0s
[22:36:29.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:71:0)
[22:36:29.519] Timer tick, asking Buildbucket for the build status
[22:36:29.657] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:29.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:72:0) after 1m0s
[22:37:29.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:72:0)
[22:37:29.749] Timer tick, asking Buildbucket for the build status
[22:37:30.193] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:30.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:73:0) after 1m0s
[22:38:30.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:73:0)
[22:38:30.217] Timer tick, asking Buildbucket for the build status
[22:38:30.715] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:30.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:74:0) after 1m0s
[22:39:30.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:74:0)
[22:39:30.838] Timer tick, asking Buildbucket for the build status
[22:39:30.972] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:30.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:75:0) after 1m0s
[22:40:31.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:75:0)
[22:40:31.054] Timer tick, asking Buildbucket for the build status
[22:40:31.293] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:31.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:76:0) after 1m0s
[22:41:31.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:76:0)
[22:41:31.328] Timer tick, asking Buildbucket for the build status
[22:41:31.455] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:31.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:77:0) after 1m0s
[22:42:31.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:77:0)
[22:42:31.554] Timer tick, asking Buildbucket for the build status
[22:42:31.674] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:31.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:78:0) after 1m0s
[22:43:31.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:78:0)
[22:43:31.810] Timer tick, asking Buildbucket for the build status
[22:43:31.938] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:31.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:79:0) after 1m0s
[22:44:32.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:79:0)
[22:44:32.046] Timer tick, asking Buildbucket for the build status
[22:44:32.180] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:32.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:80:0) after 1m0s
[22:45:32.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:80:0)
[22:45:32.256] Timer tick, asking Buildbucket for the build status
[22:45:32.379] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:32.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:81:0) after 1m0s
[22:46:32.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:81:0)
[22:46:32.582] Timer tick, asking Buildbucket for the build status
[22:46:32.743] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:32.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:82:0) after 1m0s
[22:47:32.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:82:0)
[22:47:32.880] Timer tick, asking Buildbucket for the build status
[22:47:33.008] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:33.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:83:0) after 1m0s
[22:48:33.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:83:0)
[22:48:33.223] Timer tick, asking Buildbucket for the build status
[22:48:33.487] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:33.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:84:0) after 1m0s
[22:49:33.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:84:0)
[22:49:33.633] Timer tick, asking Buildbucket for the build status
[22:49:33.998] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:33.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:85:0) after 1m0s
[22:50:34.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:85:0)
[22:50:34.225] Timer tick, asking Buildbucket for the build status
[22:50:34.448] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:34.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:86:0) after 1m0s
[22:51:34.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:86:0)
[22:51:34.602] Timer tick, asking Buildbucket for the build status
[22:51:34.692] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:34.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:87:0) after 1m0s
[22:52:34.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:87:0)
[22:52:34.752] Timer tick, asking Buildbucket for the build status
[22:52:35.250] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:35.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:88:0) after 1m0s
[22:53:35.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:88:0)
[22:53:35.307] Timer tick, asking Buildbucket for the build status
[22:53:35.398] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:35.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:89:0) after 1m0s
[22:54:35.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:89:0)
[22:54:35.423] Timer tick, asking Buildbucket for the build status
[22:54:35.608] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:35.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:90:0) after 1m0s
[22:55:35.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:90:0)
[22:55:35.652] Timer tick, asking Buildbucket for the build status
[22:55:35.801] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:35.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:91:0) after 1m0s
[22:56:35.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:91:0)
[22:56:35.899] Timer tick, asking Buildbucket for the build status
[22:56:36.015] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:36.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:92:0) after 1m0s
[22:57:36.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:92:0)
[22:57:36.086] Timer tick, asking Buildbucket for the build status
[22:57:36.186] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:36.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:93:0) after 1m0s
[22:58:36.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:93:0)
[22:58:36.243] Timer tick, asking Buildbucket for the build status
[22:58:36.330] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:36.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:94:0) after 1m0s
[22:59:36.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:94:0)
[22:59:36.396] Timer tick, asking Buildbucket for the build status
[22:59:36.540] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:36.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:95:0) after 1m0s
[23:00:36.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:95:0)
[23:00:36.650] Timer tick, asking Buildbucket for the build status
[23:00:36.929] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:36.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:96:0) after 1m0s
[23:01:36.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:96:0)
[23:01:36.955] Timer tick, asking Buildbucket for the build status
[23:01:37.085] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:37.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:97:0) after 1m0s
[23:02:37.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:97:0)
[23:02:37.172] Timer tick, asking Buildbucket for the build status
[23:02:37.302] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:37.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:98:0) after 1m0s
[23:03:37.450] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:98:0)
[23:03:37.450] Timer tick, asking Buildbucket for the build status
[23:03:37.636] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:37.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:99:0) after 1m0s
[23:04:37.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:99:0)
[23:04:37.679] Timer tick, asking Buildbucket for the build status
[23:04:37.909] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:37.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:100:0) after 1m0s
[23:05:38.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:100:0)
[23:05:38.065] Timer tick, asking Buildbucket for the build status
[23:05:38.246] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:38.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:101:0) after 1m0s
[23:06:38.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:101:0)
[23:06:38.292] Timer tick, asking Buildbucket for the build status
[23:06:38.480] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:38.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:102:0) after 1m0s
[23:07:38.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:102:0)
[23:07:38.552] Timer tick, asking Buildbucket for the build status
[23:07:38.671] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:38.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:103:0) after 1m0s
[23:08:38.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:103:0)
[23:08:38.703] Timer tick, asking Buildbucket for the build status
[23:08:38.792] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:38.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:104:0) after 1m0s
[23:09:39.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:104:0)
[23:09:39.074] Timer tick, asking Buildbucket for the build status
[23:09:39.357] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:39.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:105:0) after 1m0s
[23:10:39.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:105:0)
[23:10:39.978] Timer tick, asking Buildbucket for the build status
[23:10:41.013] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:41.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:106:0) after 1m0s
[23:11:41.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:106:0)
[23:11:41.092] Timer tick, asking Buildbucket for the build status
[23:11:41.232] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:41.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:107:0) after 1m0s
[23:12:41.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:107:0)
[23:12:41.472] Timer tick, asking Buildbucket for the build status
[23:12:41.576] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:41.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:108:0) after 1m0s
[23:13:41.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:108:0)
[23:13:41.826] Timer tick, asking Buildbucket for the build status
[23:13:41.918] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:41.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:109:0) after 1m0s
[23:14:42.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:109:0)
[23:14:42.082] Timer tick, asking Buildbucket for the build status
[23:14:42.180] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:42.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:110:0) after 1m0s
[23:15:42.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:110:0)
[23:15:42.433] Timer tick, asking Buildbucket for the build status
[23:15:42.615] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:42.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:111:0) after 1m0s
[23:16:42.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:111:0)
[23:16:42.777] Timer tick, asking Buildbucket for the build status
[23:16:43.186] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:43.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:112:0) after 1m0s
[23:17:43.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:112:0)
[23:17:43.280] Timer tick, asking Buildbucket for the build status
[23:17:43.621] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:43.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:113:0) after 1m0s
[23:18:43.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:113:0)
[23:18:43.675] Timer tick, asking Buildbucket for the build status
[23:18:43.785] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:43.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:114:0) after 1m0s
[23:19:43.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:114:0)
[23:19:43.820] Timer tick, asking Buildbucket for the build status
[23:19:43.926] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:43.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:115:0) after 1m0s
[23:20:44.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:115:0)
[23:20:44.004] Timer tick, asking Buildbucket for the build status
[23:20:44.114] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:44.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:116:0) after 1m0s
[23:21:44.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:116:0)
[23:21:44.160] Timer tick, asking Buildbucket for the build status
[23:21:44.272] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:44.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:117:0) after 1m0s
[23:22:44.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:117:0)
[23:22:44.309] Timer tick, asking Buildbucket for the build status
[23:22:44.763] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:44.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:118:0) after 1m0s
[23:23:44.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:118:0)
[23:23:44.868] Timer tick, asking Buildbucket for the build status
[23:23:45.361] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:45.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:119:0) after 1m0s
[23:24:45.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:119:0)
[23:24:45.461] Timer tick, asking Buildbucket for the build status
[23:24:45.567] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:45.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:120:0) after 1m0s
[23:25:45.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:120:0)
[23:25:45.584] Timer tick, asking Buildbucket for the build status
[23:25:45.851] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:45.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:121:0) after 1m0s
[23:26:45.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:121:0)
[23:26:45.981] Timer tick, asking Buildbucket for the build status
[23:26:46.196] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:46.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:122:0) after 1m0s
[23:27:46.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:122:0)
[23:27:46.218] Timer tick, asking Buildbucket for the build status
[23:27:46.334] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:46.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:123:0) after 1m0s
[23:28:46.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:123:0)
[23:28:46.478] Timer tick, asking Buildbucket for the build status
[23:28:46.773] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:46.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:124:0) after 1m0s
[23:29:46.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:124:0)
[23:29:46.801] Timer tick, asking Buildbucket for the build status
[23:29:46.936] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:46.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:125:0) after 1m0s
[23:30:46.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:125:0)
[23:30:46.964] Timer tick, asking Buildbucket for the build status
[23:30:47.117] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:47.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:126:0) after 1m0s
[23:31:47.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:126:0)
[23:31:47.228] Timer tick, asking Buildbucket for the build status
[23:31:47.325] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:47.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:127:0) after 1m0s
[23:32:47.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:127:0)
[23:32:47.638] Timer tick, asking Buildbucket for the build status
[23:32:47.735] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:47.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:128:0) after 1m0s
[23:33:47.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:128:0)
[23:33:47.767] Timer tick, asking Buildbucket for the build status
[23:33:47.857] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:47.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:129:0) after 1m0s
[23:34:48.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:129:0)
[23:34:48.226] Timer tick, asking Buildbucket for the build status
[23:34:48.673] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:48.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:130:0) after 1m0s
[23:35:48.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:130:0)
[23:35:48.740] Timer tick, asking Buildbucket for the build status
[23:35:48.978] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:48.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:131:0) after 1m0s
[23:36:49.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:131:0)
[23:36:49.899] Timer tick, asking Buildbucket for the build status
[23:36:50.086] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:50.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:132:0) after 1m0s
[23:37:50.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:132:0)
[23:37:50.174] Timer tick, asking Buildbucket for the build status
[23:37:50.323] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:50.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:133:0) after 1m0s
[23:38:57.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:133:0)
[23:38:57.930] Timer tick, asking Buildbucket for the build status
[23:38:58.050] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:58.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:134:0) after 1m0s
[23:39:58.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:134:0)
[23:39:58.182] Timer tick, asking Buildbucket for the build status
[23:39:58.285] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:58.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:135:0) after 1m0s
[23:40:58.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:135:0)
[23:40:58.309] Timer tick, asking Buildbucket for the build status
[23:40:58.962] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:58.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:136:0) after 1m0s
[23:41:59.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:136:0)
[23:41:59.095] Timer tick, asking Buildbucket for the build status
[23:41:59.392] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:59.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:137:0) after 1m0s
[23:42:59.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:137:0)
[23:42:59.530] Timer tick, asking Buildbucket for the build status
[23:42:59.627] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:59.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:138:0) after 1m0s
[23:43:59.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:138:0)
[23:43:59.706] Timer tick, asking Buildbucket for the build status
[23:44:00.090] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:00.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:139:0) after 1m0s
[23:45:00.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:139:0)
[23:45:00.172] Timer tick, asking Buildbucket for the build status
[23:45:00.516] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:00.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:140:0) after 1m0s
[23:46:00.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:140:0)
[23:46:00.594] Timer tick, asking Buildbucket for the build status
[23:46:00.808] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:00.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:141:0) after 1m0s
[23:47:00.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:141:0)
[23:47:00.836] Timer tick, asking Buildbucket for the build status
[23:47:01.102] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:01.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:142:0) after 1m0s
[23:48:01.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:142:0)
[23:48:01.196] Timer tick, asking Buildbucket for the build status
[23:48:01.548] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:01.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:143:0) after 1m0s
[23:49:01.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:143:0)
[23:49:01.778] Timer tick, asking Buildbucket for the build status
[23:49:02.122] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:02.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:144:0) after 1m0s
[23:50:02.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:144:0)
[23:50:02.156] Timer tick, asking Buildbucket for the build status
[23:50:02.285] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:02.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:145:0) after 1m0s
[23:51:02.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:145:0)
[23:51:02.395] Timer tick, asking Buildbucket for the build status
[23:51:02.503] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:02.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:146:0) after 1m0s
[23:52:02.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:146:0)
[23:52:02.580] Timer tick, asking Buildbucket for the build status
[23:52:02.886] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:02.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:147:0) after 1m0s
[23:53:02.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:147:0)
[23:53:02.914] Timer tick, asking Buildbucket for the build status
[23:53:03.075] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:03.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:148:0) after 1m0s
[23:54:03.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:148:0)
[23:54:03.178] Timer tick, asking Buildbucket for the build status
[23:54:03.594] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:03.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:149:0) after 1m0s
[23:55:03.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:149:0)
[23:55:03.623] Timer tick, asking Buildbucket for the build status
[23:55:03.873] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:03.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:150:0) after 1m0s
[23:56:03.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:150:0)
[23:56:03.921] Timer tick, asking Buildbucket for the build status
[23:56:04.026] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:04.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:151:0) after 1m0s
[23:57:04.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:151:0)
[23:57:04.021] Timer tick, asking Buildbucket for the build status
[23:57:04.457] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:04.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:152:0) after 1m0s
[23:58:04.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:152:0)
[23:58:04.568] Timer tick, asking Buildbucket for the build status
[23:58:04.971] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:04.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:153:0) after 1m0s
[23:59:05.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:153:0)
[23:59:05.124] Timer tick, asking Buildbucket for the build status
[23:59:05.401] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:05.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:154:0) after 1m0s
[00:00:05.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:154:0)
[00:00:05.473] Timer tick, asking Buildbucket for the build status
[00:00:05.944] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:05.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:155:0) after 1m0s
[00:01:06.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:155:0)
[00:01:06.012] Timer tick, asking Buildbucket for the build status
[00:01:06.174] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:06.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:156:0) after 1m0s
[00:02:06.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:156:0)
[00:02:06.249] Timer tick, asking Buildbucket for the build status
[00:02:06.434] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:06.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:157:0) after 1m0s
[00:03:06.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:157:0)
[00:03:06.537] Timer tick, asking Buildbucket for the build status
[00:03:07.163] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:07.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:158:0) after 1m0s
[00:04:07.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:158:0)
[00:04:07.282] Timer tick, asking Buildbucket for the build status
[00:04:07.578] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:07.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:159:0) after 1m0s
[00:05:07.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:159:0)
[00:05:07.639] Timer tick, asking Buildbucket for the build status
[00:05:07.859] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:07.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:160:0) after 1m0s
[00:06:07.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:160:0)
[00:06:07.856] Timer tick, asking Buildbucket for the build status
[00:06:07.986] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:07.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:161:0) after 1m0s
[00:07:08.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:161:0)
[00:07:08.056] Timer tick, asking Buildbucket for the build status
[00:07:08.212] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:08.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:162:0) after 1m0s
[00:08:08.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:162:0)
[00:08:08.376] Timer tick, asking Buildbucket for the build status
[00:08:08.515] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:08.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:163:0) after 1m0s
[00:09:08.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:163:0)
[00:09:08.570] Timer tick, asking Buildbucket for the build status
[00:09:08.707] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:08.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:164:0) after 1m0s
[00:10:08.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:164:0)
[00:10:08.867] Timer tick, asking Buildbucket for the build status
[00:10:08.962] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:08.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:165:0) after 1m0s
[00:11:09.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:165:0)
[00:11:09.115] Timer tick, asking Buildbucket for the build status
[00:11:09.358] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:09.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:166:0) after 1m0s
[00:12:09.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:166:0)
[00:12:09.497] Timer tick, asking Buildbucket for the build status
[00:12:09.635] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:09.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:167:0) after 1m0s
[00:13:09.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:167:0)
[00:13:09.703] Timer tick, asking Buildbucket for the build status
[00:13:09.928] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:09.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:168:0) after 1m0s
[00:14:09.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:168:0)
[00:14:09.942] Timer tick, asking Buildbucket for the build status
[00:14:10.093] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:10.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:169:0) after 1m0s
[00:15:10.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:169:0)
[00:15:10.299] Timer tick, asking Buildbucket for the build status
[00:15:10.528] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:10.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:170:0) after 1m0s
[00:16:10.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:170:0)
[00:16:10.527] Timer tick, asking Buildbucket for the build status
[00:16:10.640] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:10.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:171:0) after 1m0s
[00:17:10.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:171:0)
[00:17:10.701] Timer tick, asking Buildbucket for the build status
[00:17:10.937] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:10.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:172:0) after 1m0s
[00:18:11.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:172:0)
[00:18:11.088] Timer tick, asking Buildbucket for the build status
[00:18:11.259] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:11.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:173:0) after 1m0s
[00:19:11.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:173:0)
[00:19:11.332] Timer tick, asking Buildbucket for the build status
[00:19:11.692] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:11.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:174:0) after 1m0s
[00:20:11.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:174:0)
[00:20:11.789] Timer tick, asking Buildbucket for the build status
[00:20:12.075] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:12.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:175:0) after 1m0s
[00:21:12.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:175:0)
[00:21:12.101] Timer tick, asking Buildbucket for the build status
[00:21:12.249] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:12.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:176:0) after 1m0s
[00:22:12.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:176:0)
[00:22:12.374] Timer tick, asking Buildbucket for the build status
[00:22:12.698] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:12.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:177:0) after 1m0s
[00:23:12.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:177:0)
[00:23:12.844] Timer tick, asking Buildbucket for the build status
[00:23:12.960] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:12.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:178:0) after 1m0s
[00:24:13.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:178:0)
[00:24:13.098] Timer tick, asking Buildbucket for the build status
[00:24:13.434] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:13.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:179:0) after 1m0s
[00:25:13.629] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:179:0)
[00:25:13.629] Timer tick, asking Buildbucket for the build status
[00:25:13.748] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:13.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:180:0) after 1m0s
[00:26:13.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:180:0)
[00:26:13.812] Timer tick, asking Buildbucket for the build status
[00:26:14.035] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:14.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:181:0) after 1m0s
[00:27:14.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:181:0)
[00:27:14.127] Timer tick, asking Buildbucket for the build status
[00:27:14.369] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:14.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:182:0) after 1m0s
[00:28:14.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:182:0)
[00:28:14.559] Timer tick, asking Buildbucket for the build status
[00:28:14.724] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:14.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:183:0) after 1m0s
[00:29:14.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:183:0)
[00:29:14.849] Timer tick, asking Buildbucket for the build status
[00:29:15.153] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:15.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:184:0) after 1m0s
[00:30:15.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:184:0)
[00:30:15.206] Timer tick, asking Buildbucket for the build status
[00:30:15.482] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:15.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:185:0) after 1m0s
[00:31:15.567] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:185:0)
[00:31:15.567] Timer tick, asking Buildbucket for the build status
[00:31:15.735] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:15.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:186:0) after 1m0s
[00:32:15.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:186:0)
[00:32:15.842] Timer tick, asking Buildbucket for the build status
[00:32:16.015] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:16.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:187:0) after 1m0s
[00:33:16.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:187:0)
[00:33:16.052] Timer tick, asking Buildbucket for the build status
[00:33:16.155] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:16.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:188:0) after 1m0s
[00:34:16.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:188:0)
[00:34:16.260] Timer tick, asking Buildbucket for the build status
[00:34:16.592] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:16.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:189:0) after 1m0s
[00:35:16.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:189:0)
[00:35:16.669] Timer tick, asking Buildbucket for the build status
[00:35:16.803] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:16.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:190:0) after 1m0s
[00:36:16.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:190:0)
[00:36:16.889] Timer tick, asking Buildbucket for the build status
[00:36:17.024] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:17.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:191:0) after 1m0s
[00:37:17.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:191:0)
[00:37:17.207] Timer tick, asking Buildbucket for the build status
[00:37:17.492] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:17.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:192:0) after 1m0s
[00:38:17.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:192:0)
[00:38:17.481] Timer tick, asking Buildbucket for the build status
[00:38:17.595] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:17.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:193:0) after 1m0s
[00:39:17.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:193:0)
[00:39:17.738] Timer tick, asking Buildbucket for the build status
[00:39:18.166] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:18.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:194:0) after 1m0s
[00:40:18.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:194:0)
[00:40:18.266] Timer tick, asking Buildbucket for the build status
[00:40:18.359] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:18.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:195:0) after 1m0s
[00:41:18.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:195:0)
[00:41:18.397] Timer tick, asking Buildbucket for the build status
[00:41:18.489] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:18.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:196:0) after 1m0s
[00:42:18.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:196:0)
[00:42:18.575] Timer tick, asking Buildbucket for the build status
[00:42:18.681] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:18.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:197:0) after 1m0s
[00:43:18.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:197:0)
[00:43:18.771] Timer tick, asking Buildbucket for the build status
[00:43:18.872] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:18.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:198:0) after 1m0s
[00:44:18.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:198:0)
[00:44:18.997] Timer tick, asking Buildbucket for the build status
[00:44:19.190] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:19.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:199:0) after 1m0s
[00:45:19.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:199:0)
[00:45:19.250] Timer tick, asking Buildbucket for the build status
[00:45:19.408] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:19.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:200:0) after 1m0s
[00:46:19.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:200:0)
[00:46:19.598] Timer tick, asking Buildbucket for the build status
[00:46:19.712] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:19.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:201:0) after 1m0s
[00:47:20.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:201:0)
[00:47:20.011] Timer tick, asking Buildbucket for the build status
[00:47:20.276] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:20.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:202:0) after 1m0s
[00:48:20.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:202:0)
[00:48:20.367] Timer tick, asking Buildbucket for the build status
[00:48:20.679] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:20.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:203:0) after 1m0s
[00:49:20.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:203:0)
[00:49:20.750] Timer tick, asking Buildbucket for the build status
[00:49:20.881] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:20.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:204:0) after 1m0s
[00:50:21.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:204:0)
[00:50:21.026] Timer tick, asking Buildbucket for the build status
[00:50:21.212] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:21.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:205:0) after 1m0s
[00:51:21.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:205:0)
[00:51:21.269] Timer tick, asking Buildbucket for the build status
[00:51:21.375] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:21.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:206:0) after 1m0s
[00:52:21.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:206:0)
[00:52:21.411] Timer tick, asking Buildbucket for the build status
[00:52:21.871] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:21.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:207:0) after 1m0s
[00:53:21.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:207:0)
[00:53:21.950] Timer tick, asking Buildbucket for the build status
[00:53:22.093] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:22.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:208:0) after 1m0s
[00:54:22.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:208:0)
[00:54:22.155] Timer tick, asking Buildbucket for the build status
[00:54:22.395] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:22.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:209:0) after 1m0s
[00:55:22.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:209:0)
[00:55:22.427] Timer tick, asking Buildbucket for the build status
[00:55:22.762] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:22.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:210:0) after 1m0s
[00:56:22.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:210:0)
[00:56:22.932] Timer tick, asking Buildbucket for the build status
[00:56:23.121] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:23.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:211:0) after 1m0s
[00:57:23.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:211:0)
[00:57:23.249] Timer tick, asking Buildbucket for the build status
[00:57:23.440] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:23.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:212:0) after 1m0s
[00:58:23.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:212:0)
[00:58:23.475] Timer tick, asking Buildbucket for the build status
[00:58:23.935] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:23.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:213:0) after 1m0s
[00:59:24.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:213:0)
[00:59:24.006] Timer tick, asking Buildbucket for the build status
[00:59:24.206] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:24.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:214:0) after 1m0s
[01:00:24.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:214:0)
[01:00:24.266] Timer tick, asking Buildbucket for the build status
[01:00:24.388] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:24.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:215:0) after 1m0s
[01:01:24.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:215:0)
[01:01:24.491] Timer tick, asking Buildbucket for the build status
[01:01:24.812] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:24.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:216:0) after 1m0s
[01:02:24.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:216:0)
[01:02:24.959] Timer tick, asking Buildbucket for the build status
[01:02:25.302] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:25.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:217:0) after 1m0s
[01:03:25.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:217:0)
[01:03:25.341] Timer tick, asking Buildbucket for the build status
[01:03:25.548] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:25.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:218:0) after 1m0s
[01:04:25.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:218:0)
[01:04:25.597] Timer tick, asking Buildbucket for the build status
[01:04:25.747] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:25.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:219:0) after 1m0s
[01:05:25.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:219:0)
[01:05:25.850] Timer tick, asking Buildbucket for the build status
[01:05:25.979] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:25.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:220:0) after 1m0s
[01:06:26.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:220:0)
[01:06:26.031] Timer tick, asking Buildbucket for the build status
[01:06:26.194] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:26.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:221:0) after 1m0s
[01:07:26.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:221:0)
[01:07:26.294] Timer tick, asking Buildbucket for the build status
[01:07:26.578] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:26.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:222:0) after 1m0s
[01:08:26.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:222:0)
[01:08:26.741] Timer tick, asking Buildbucket for the build status
[01:08:26.998] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:26.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:223:0) after 1m0s
[01:09:27.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:223:0)
[01:09:27.027] Timer tick, asking Buildbucket for the build status
[01:09:27.992] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:27.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:224:0) after 1m0s
[01:10:28.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:224:0)
[01:10:28.023] Timer tick, asking Buildbucket for the build status
[01:10:28.447] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:28.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:225:0) after 1m0s
[01:11:28.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:225:0)
[01:11:28.481] Timer tick, asking Buildbucket for the build status
[01:11:28.835] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:28.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:226:0) after 1m0s
[01:12:28.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:226:0)
[01:12:28.819] Timer tick, asking Buildbucket for the build status
[01:12:28.955] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:28.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:227:0) after 1m0s
[01:13:28.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:227:0)
[01:13:28.993] Timer tick, asking Buildbucket for the build status
[01:13:29.095] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:29.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:228:0) after 1m0s
[01:14:29.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:228:0)
[01:14:29.111] Timer tick, asking Buildbucket for the build status
[01:14:29.220] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:29.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:229:0) after 1m0s
[01:15:29.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:229:0)
[01:15:29.219] Timer tick, asking Buildbucket for the build status
[01:15:29.610] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:29.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:230:0) after 1m0s
[01:16:29.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:230:0)
[01:16:29.657] Timer tick, asking Buildbucket for the build status
[01:16:30.014] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:30.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:231:0) after 1m0s
[01:17:30.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:231:0)
[01:17:30.076] Timer tick, asking Buildbucket for the build status
[01:17:30.189] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:30.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:232:0) after 1m0s
[01:18:30.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:232:0)
[01:18:30.226] Timer tick, asking Buildbucket for the build status
[01:18:30.357] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:30.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:233:0) after 1m0s
[01:19:30.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:233:0)
[01:19:30.386] Timer tick, asking Buildbucket for the build status
[01:19:30.657] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:30.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:234:0) after 1m0s
[01:20:30.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:234:0)
[01:20:30.685] Timer tick, asking Buildbucket for the build status
[01:20:30.991] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:30.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:235:0) after 1m0s
[01:21:30.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:235:0)
[01:21:30.988] Timer tick, asking Buildbucket for the build status
[01:21:31.242] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:31.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:236:0) after 1m0s
[01:22:31.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:236:0)
[01:22:31.327] Timer tick, asking Buildbucket for the build status
[01:22:31.682] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:31.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:237:0) after 1m0s
[01:23:31.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:237:0)
[01:23:31.782] Timer tick, asking Buildbucket for the build status
[01:23:32.233] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:32.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:238:0) after 1m0s
[01:24:32.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:238:0)
[01:24:32.275] Timer tick, asking Buildbucket for the build status
[01:24:32.465] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:32.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:239:0) after 1m0s
[01:25:32.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:239:0)
[01:25:32.469] Timer tick, asking Buildbucket for the build status
[01:25:32.559] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:32.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:240:0) after 1m0s
[01:26:32.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:240:0)
[01:26:32.593] Timer tick, asking Buildbucket for the build status
[01:26:32.855] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:32.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:241:0) after 1m0s
[01:27:32.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:241:0)
[01:27:32.971] Timer tick, asking Buildbucket for the build status
[01:27:33.127] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:33.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:242:0) after 1m0s
[01:28:33.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:242:0)
[01:28:33.192] Timer tick, asking Buildbucket for the build status
[01:28:33.410] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:33.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:243:0) after 1m0s
[01:29:33.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:243:0)
[01:29:33.406] Timer tick, asking Buildbucket for the build status
[01:29:33.535] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:33.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:244:0) after 1m0s
[01:30:33.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:244:0)
[01:30:33.669] Timer tick, asking Buildbucket for the build status
[01:30:33.877] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:33.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:245:0) after 1m0s
[01:31:33.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:245:0)
[01:31:33.904] Timer tick, asking Buildbucket for the build status
[01:31:34.076] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:34.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:246:0) after 1m0s
[01:32:34.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:246:0)
[01:32:34.189] Timer tick, asking Buildbucket for the build status
[01:32:34.594] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:34.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:247:0) after 1m0s
[01:33:34.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:247:0)
[01:33:34.658] Timer tick, asking Buildbucket for the build status
[01:33:35.095] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:35.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:248:0) after 1m0s
[01:34:35.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:248:0)
[01:34:35.213] Timer tick, asking Buildbucket for the build status
[01:34:35.409] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:35.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:249:0) after 1m0s
[01:35:35.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:249:0)
[01:35:35.525] Timer tick, asking Buildbucket for the build status
[01:35:35.777] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:35.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:250:0) after 1m0s
[01:36:35.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:250:0)
[01:36:35.986] Timer tick, asking Buildbucket for the build status
[01:36:36.189] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:36.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:251:0) after 1m0s
[01:37:36.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:251:0)
[01:37:36.425] Timer tick, asking Buildbucket for the build status
[01:37:36.524] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:36.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:252:0) after 1m0s
[01:38:36.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:252:0)
[01:38:36.601] Timer tick, asking Buildbucket for the build status
[01:38:36.696] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:36.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:253:0) after 1m0s
[01:39:36.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:253:0)
[01:39:36.950] Timer tick, asking Buildbucket for the build status
[01:39:37.155] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:37.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:254:0) after 1m0s
[01:40:37.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:254:0)
[01:40:37.267] Timer tick, asking Buildbucket for the build status
[01:40:37.388] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:37.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:255:0) after 1m0s
[01:41:37.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:255:0)
[01:41:37.417] Timer tick, asking Buildbucket for the build status
[01:41:37.777] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:37.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:256:0) after 1m0s
[01:42:37.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:256:0)
[01:42:37.924] Timer tick, asking Buildbucket for the build status
[01:42:38.110] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:38.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:257:0) after 1m0s
[01:43:38.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:257:0)
[01:43:38.221] Timer tick, asking Buildbucket for the build status
[01:43:38.323] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:38.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:258:0) after 1m0s
[01:44:38.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:258:0)
[01:44:38.360] Timer tick, asking Buildbucket for the build status
[01:44:38.648] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:38.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:259:0) after 1m0s
[01:45:38.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:259:0)
[01:45:38.712] Timer tick, asking Buildbucket for the build status
[01:45:38.800] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:38.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:260:0) after 1m0s
[01:46:38.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:260:0)
[01:46:38.814] Timer tick, asking Buildbucket for the build status
[01:46:39.211] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:39.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:261:0) after 1m0s
[01:47:39.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:261:0)
[01:47:39.270] Timer tick, asking Buildbucket for the build status
[01:47:39.402] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:39.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:262:0) after 1m0s
[01:48:39.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:262:0)
[01:48:39.473] Timer tick, asking Buildbucket for the build status
[01:48:39.906] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:39.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:263:0) after 1m0s
[01:49:39.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:263:0)
[01:49:39.938] Timer tick, asking Buildbucket for the build status
[01:49:40.207] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:40.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:264:0) after 1m0s
[01:50:40.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:264:0)
[01:50:40.276] Timer tick, asking Buildbucket for the build status
[01:50:40.375] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:40.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:265:0) after 1m0s
[01:51:40.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:265:0)
[01:51:40.434] Timer tick, asking Buildbucket for the build status
[01:51:40.572] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:40.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:266:0) after 1m0s
[01:52:40.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:266:0)
[01:52:40.603] Timer tick, asking Buildbucket for the build status
[01:52:40.709] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:40.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:267:0) after 1m0s
[01:53:40.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:267:0)
[01:53:40.741] Timer tick, asking Buildbucket for the build status
[01:53:41.162] Build 8887266976262905008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:41.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:268:0) after 1m0s
[01:53:58.873] Received PubSub notification, asking Buildbucket for the build status
[01:53:58.951] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:41.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:268:0)
[01:54:41.202] Timer tick, asking Buildbucket for the build status
[01:54:41.345] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:41.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:270:0) after 1m0s
[01:55:41.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:270:0)
[01:55:41.446] Timer tick, asking Buildbucket for the build status
[01:55:41.713] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:41.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:271:0) after 1m0s
[01:56:41.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:271:0)
[01:56:41.811] Timer tick, asking Buildbucket for the build status
[01:56:41.952] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:41.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:272:0) after 1m0s
[01:57:42.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:272:0)
[01:57:42.053] Timer tick, asking Buildbucket for the build status
[01:57:42.244] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:42.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:273:0) after 1m0s
[01:58:42.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:273:0)
[01:58:42.307] Timer tick, asking Buildbucket for the build status
[01:58:42.566] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:42.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:274:0) after 1m0s
[01:59:42.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:274:0)
[01:59:42.614] Timer tick, asking Buildbucket for the build status
[01:59:42.959] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:42.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:275:0) after 1m0s
[02:00:43.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:275:0)
[02:00:43.028] Timer tick, asking Buildbucket for the build status
[02:00:43.328] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:43.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:276:0) after 1m0s
[02:01:43.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:276:0)
[02:01:43.383] Timer tick, asking Buildbucket for the build status
[02:01:43.508] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:43.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:277:0) after 1m0s
[02:02:43.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:277:0)
[02:02:43.535] Timer tick, asking Buildbucket for the build status
[02:02:43.827] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:43.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:278:0) after 1m0s
[02:03:43.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:278:0)
[02:03:43.910] Timer tick, asking Buildbucket for the build status
[02:03:44.018] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:44.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:279:0) after 1m0s
[02:04:44.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:279:0)
[02:04:44.128] Timer tick, asking Buildbucket for the build status
[02:04:44.237] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:44.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:280:0) after 1m0s
[02:05:44.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:280:0)
[02:05:44.409] Timer tick, asking Buildbucket for the build status
[02:05:44.545] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:44.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:281:0) after 1m0s
[02:06:44.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:281:0)
[02:06:44.606] Timer tick, asking Buildbucket for the build status
[02:06:44.782] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:44.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:282:0) after 1m0s
[02:07:44.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:282:0)
[02:07:44.848] Timer tick, asking Buildbucket for the build status
[02:07:44.940] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:44.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:283:0) after 1m0s
[02:08:45.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:283:0)
[02:08:45.031] Timer tick, asking Buildbucket for the build status
[02:08:45.274] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:45.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:284:0) after 1m0s
[02:09:45.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:284:0)
[02:09:45.318] Timer tick, asking Buildbucket for the build status
[02:09:46.193] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:46.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:285:0) after 1m0s
[02:10:46.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:285:0)
[02:10:46.245] Timer tick, asking Buildbucket for the build status
[02:10:46.617] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:46.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:286:0) after 1m0s
[02:11:46.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:286:0)
[02:11:46.644] Timer tick, asking Buildbucket for the build status
[02:11:46.760] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:46.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:287:0) after 1m0s
[02:12:46.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:287:0)
[02:12:46.863] Timer tick, asking Buildbucket for the build status
[02:12:47.283] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:47.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:288:0) after 1m0s
[02:13:47.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:288:0)
[02:13:47.471] Timer tick, asking Buildbucket for the build status
[02:13:47.560] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:47.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:289:0) after 1m0s
[02:14:47.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:289:0)
[02:14:47.741] Timer tick, asking Buildbucket for the build status
[02:14:47.867] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:47.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:290:0) after 1m0s
[02:15:47.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:290:0)
[02:15:47.935] Timer tick, asking Buildbucket for the build status
[02:15:48.020] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:48.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:291:0) after 1m0s
[02:16:48.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:291:0)
[02:16:48.133] Timer tick, asking Buildbucket for the build status
[02:16:48.333] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:48.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:292:0) after 1m0s
[02:17:48.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:292:0)
[02:17:48.386] Timer tick, asking Buildbucket for the build status
[02:17:48.502] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:48.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:293:0) after 1m0s
[02:18:48.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:293:0)
[02:18:48.659] Timer tick, asking Buildbucket for the build status
[02:18:48.800] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:48.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:294:0) after 1m0s
[02:19:48.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:294:0)
[02:19:48.849] Timer tick, asking Buildbucket for the build status
[02:19:48.970] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:48.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:295:0) after 1m0s
[02:20:49.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:295:0)
[02:20:49.289] Timer tick, asking Buildbucket for the build status
[02:20:49.456] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:49.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:296:0) after 1m0s
[02:21:49.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:296:0)
[02:21:49.613] Timer tick, asking Buildbucket for the build status
[02:21:49.715] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:49.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:297:0) after 1m0s
[02:22:49.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:297:0)
[02:22:49.779] Timer tick, asking Buildbucket for the build status
[02:22:50.069] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:50.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:298:0) after 1m0s
[02:23:50.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:298:0)
[02:23:50.156] Timer tick, asking Buildbucket for the build status
[02:23:50.349] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:50.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:299:0) after 1m0s
[02:24:50.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:299:0)
[02:24:50.404] Timer tick, asking Buildbucket for the build status
[02:24:50.506] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:50.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:300:0) after 1m0s
[02:25:50.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:300:0)
[02:25:50.561] Timer tick, asking Buildbucket for the build status
[02:25:50.959] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:50.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:301:0) after 1m0s
[02:26:51.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:301:0)
[02:26:51.143] Timer tick, asking Buildbucket for the build status
[02:26:51.317] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:51.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:302:0) after 1m0s
[02:27:51.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:302:0)
[02:27:51.403] Timer tick, asking Buildbucket for the build status
[02:27:51.664] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:51.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:303:0) after 1m0s
[02:28:51.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:303:0)
[02:28:51.704] Timer tick, asking Buildbucket for the build status
[02:28:51.822] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:51.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:304:0) after 1m0s
[02:29:51.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:304:0)
[02:29:51.964] Timer tick, asking Buildbucket for the build status
[02:29:52.069] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:52.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:305:0) after 1m0s
[02:30:52.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:305:0)
[02:30:52.196] Timer tick, asking Buildbucket for the build status
[02:30:52.591] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:52.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:306:0) after 1m0s
[02:31:52.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:306:0)
[02:31:52.619] Timer tick, asking Buildbucket for the build status
[02:31:52.915] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:52.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:307:0) after 1m0s
[02:32:53.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:307:0)
[02:32:53.019] Timer tick, asking Buildbucket for the build status
[02:32:53.183] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:53.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:308:0) after 1m0s
[02:33:53.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:308:0)
[02:33:53.218] Timer tick, asking Buildbucket for the build status
[02:33:53.326] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:53.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:309:0) after 1m0s
[02:34:53.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:309:0)
[02:34:53.364] Timer tick, asking Buildbucket for the build status
[02:34:53.476] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:53.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:310:0) after 1m0s
[02:35:53.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:310:0)
[02:35:53.555] Timer tick, asking Buildbucket for the build status
[02:35:53.652] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:53.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:311:0) after 1m0s
[02:36:53.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:311:0)
[02:36:53.849] Timer tick, asking Buildbucket for the build status
[02:36:54.138] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:54.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:312:0) after 1m0s
[02:37:54.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:312:0)
[02:37:54.267] Timer tick, asking Buildbucket for the build status
[02:37:54.368] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:54.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:313:0) after 1m0s
[02:38:54.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:313:0)
[02:38:54.446] Timer tick, asking Buildbucket for the build status
[02:38:54.534] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:54.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:314:0) after 1m0s
[02:39:54.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:314:0)
[02:39:54.585] Timer tick, asking Buildbucket for the build status
[02:39:54.795] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:54.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:315:0) after 1m0s
[02:40:54.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:315:0)
[02:40:54.897] Timer tick, asking Buildbucket for the build status
[02:40:55.093] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:55.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:316:0) after 1m0s
[02:41:55.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:316:0)
[02:41:55.252] Timer tick, asking Buildbucket for the build status
[02:41:55.569] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:55.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:317:0) after 1m0s
[02:42:55.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:317:0)
[02:42:55.559] Timer tick, asking Buildbucket for the build status
[02:42:55.669] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:55.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:318:0) after 1m0s
[02:43:55.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:318:0)
[02:43:55.847] Timer tick, asking Buildbucket for the build status
[02:43:56.322] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:56.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:319:0) after 1m0s
[02:44:56.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:319:0)
[02:44:56.557] Timer tick, asking Buildbucket for the build status
[02:44:56.690] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:56.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:320:0) after 1m0s
[02:45:56.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:320:0)
[02:45:56.812] Timer tick, asking Buildbucket for the build status
[02:45:56.995] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:56.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:321:0) after 1m0s
[02:46:57.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:321:0)
[02:46:57.084] Timer tick, asking Buildbucket for the build status
[02:46:57.181] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:57.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:322:0) after 1m0s
[02:47:57.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:322:0)
[02:47:57.302] Timer tick, asking Buildbucket for the build status
[02:47:57.441] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:57.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:323:0) after 1m0s
[02:48:57.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:323:0)
[02:48:57.474] Timer tick, asking Buildbucket for the build status
[02:48:57.585] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:57.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:324:0) after 1m0s
[02:49:57.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:324:0)
[02:49:57.677] Timer tick, asking Buildbucket for the build status
[02:49:57.798] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:57.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:325:0) after 1m0s
[02:50:57.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:325:0)
[02:50:57.902] Timer tick, asking Buildbucket for the build status
[02:50:58.021] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:58.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:326:0) after 1m0s
[02:51:58.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:326:0)
[02:51:58.092] Timer tick, asking Buildbucket for the build status
[02:51:58.217] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:58.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:327:0) after 1m0s
[02:52:58.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:327:0)
[02:52:58.276] Timer tick, asking Buildbucket for the build status
[02:52:58.390] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:328:0) after 1m0s
[02:53:58.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:328:0)
[02:53:58.486] Timer tick, asking Buildbucket for the build status
[02:53:58.749] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:329:0) after 1m0s
[02:54:58.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:329:0)
[02:54:58.784] Timer tick, asking Buildbucket for the build status
[02:54:58.948] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:58.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:330:0) after 1m0s
[02:55:58.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:330:0)
[02:55:58.986] Timer tick, asking Buildbucket for the build status
[02:55:59.098] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:59.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:331:0) after 1m0s
[02:56:59.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:331:0)
[02:56:59.141] Timer tick, asking Buildbucket for the build status
[02:56:59.250] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:59.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:332:0) after 1m0s
[02:57:59.422] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:332:0)
[02:57:59.422] Timer tick, asking Buildbucket for the build status
[02:57:59.570] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:59.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:333:0) after 1m0s
[02:58:59.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:333:0)
[02:58:59.631] Timer tick, asking Buildbucket for the build status
[02:58:59.746] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:59.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:334:0) after 1m0s
[02:59:59.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:334:0)
[02:59:59.825] Timer tick, asking Buildbucket for the build status
[02:59:59.987] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:59.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:335:0) after 1m0s
[03:01:00.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:335:0)
[03:01:00.021] Timer tick, asking Buildbucket for the build status
[03:01:00.313] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:00.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:336:0) after 1m0s
[03:02:00.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:336:0)
[03:02:00.416] Timer tick, asking Buildbucket for the build status
[03:02:00.527] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:00.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:337:0) after 1m0s
[03:03:00.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:337:0)
[03:03:00.574] Timer tick, asking Buildbucket for the build status
[03:03:01.102] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:01.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:338:0) after 1m0s
[03:04:01.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:338:0)
[03:04:01.293] Timer tick, asking Buildbucket for the build status
[03:04:01.558] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:01.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:339:0) after 1m0s
[03:05:01.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:339:0)
[03:05:01.665] Timer tick, asking Buildbucket for the build status
[03:05:01.855] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:01.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:340:0) after 1m0s
[03:06:01.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:340:0)
[03:06:01.929] Timer tick, asking Buildbucket for the build status
[03:06:02.062] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:02.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:341:0) after 1m0s
[03:07:02.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:341:0)
[03:07:02.136] Timer tick, asking Buildbucket for the build status
[03:07:02.426] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:02.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:342:0) after 1m0s
[03:08:02.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:342:0)
[03:08:02.476] Timer tick, asking Buildbucket for the build status
[03:08:02.621] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:02.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:343:0) after 1m0s
[03:09:02.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:343:0)
[03:09:02.707] Timer tick, asking Buildbucket for the build status
[03:09:02.854] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:02.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:344:0) after 1m0s
[03:10:02.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:344:0)
[03:10:02.884] Timer tick, asking Buildbucket for the build status
[03:10:03.246] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:03.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:345:0) after 1m0s
[03:11:03.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:345:0)
[03:11:03.268] Timer tick, asking Buildbucket for the build status
[03:11:03.369] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:03.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:346:0) after 1m0s
[03:12:03.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:346:0)
[03:12:03.424] Timer tick, asking Buildbucket for the build status
[03:12:03.582] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:03.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:347:0) after 1m0s
[03:13:03.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:347:0)
[03:13:03.649] Timer tick, asking Buildbucket for the build status
[03:13:03.827] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:03.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:348:0) after 1m0s
[03:14:03.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:348:0)
[03:14:03.865] Timer tick, asking Buildbucket for the build status
[03:14:04.371] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:04.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:349:0) after 1m0s
[03:15:04.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:349:0)
[03:15:04.575] Timer tick, asking Buildbucket for the build status
[03:15:04.774] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:04.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:350:0) after 1m0s
[03:16:04.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:350:0)
[03:16:04.823] Timer tick, asking Buildbucket for the build status
[03:16:04.937] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:04.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:351:0) after 1m0s
[03:17:05.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:351:0)
[03:17:05.186] Timer tick, asking Buildbucket for the build status
[03:17:05.582] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:05.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:352:0) after 1m0s
[03:18:05.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:352:0)
[03:18:05.618] Timer tick, asking Buildbucket for the build status
[03:18:06.031] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:06.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:353:0) after 1m0s
[03:19:06.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:353:0)
[03:19:06.176] Timer tick, asking Buildbucket for the build status
[03:19:06.343] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:06.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:354:0) after 1m0s
[03:20:06.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:354:0)
[03:20:06.447] Timer tick, asking Buildbucket for the build status
[03:20:06.709] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:06.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:355:0) after 1m0s
[03:21:06.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:355:0)
[03:21:06.836] Timer tick, asking Buildbucket for the build status
[03:21:06.943] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:06.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:356:0) after 1m0s
[03:22:07.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:356:0)
[03:22:07.059] Timer tick, asking Buildbucket for the build status
[03:22:07.564] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:07.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:357:0) after 1m0s
[03:23:07.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:357:0)
[03:23:07.733] Timer tick, asking Buildbucket for the build status
[03:23:07.954] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:07.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:358:0) after 1m0s
[03:24:08.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:358:0)
[03:24:08.020] Timer tick, asking Buildbucket for the build status
[03:24:08.137] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:08.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:359:0) after 1m0s
[03:25:08.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:359:0)
[03:25:08.218] Timer tick, asking Buildbucket for the build status
[03:25:08.370] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:08.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:360:0) after 1m0s
[03:26:08.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:360:0)
[03:26:08.487] Timer tick, asking Buildbucket for the build status
[03:26:08.610] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:08.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:361:0) after 1m0s
[03:27:08.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:361:0)
[03:27:08.644] Timer tick, asking Buildbucket for the build status
[03:27:08.826] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:08.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:362:0) after 1m0s
[03:28:08.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:362:0)
[03:28:08.873] Timer tick, asking Buildbucket for the build status
[03:28:09.004] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:09.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:363:0) after 1m0s
[03:29:09.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:363:0)
[03:29:09.198] Timer tick, asking Buildbucket for the build status
[03:29:09.324] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:09.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:364:0) after 1m0s
[03:30:09.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:364:0)
[03:30:09.460] Timer tick, asking Buildbucket for the build status
[03:30:09.640] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:09.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:365:0) after 1m0s
[03:31:09.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:365:0)
[03:31:09.726] Timer tick, asking Buildbucket for the build status
[03:31:09.941] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:09.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:366:0) after 1m0s
[03:32:10.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:366:0)
[03:32:10.147] Timer tick, asking Buildbucket for the build status
[03:32:10.580] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:10.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:367:0) after 1m0s
[03:33:10.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:367:0)
[03:33:10.719] Timer tick, asking Buildbucket for the build status
[03:33:10.841] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:10.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:368:0) after 1m0s
[03:34:10.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:368:0)
[03:34:10.866] Timer tick, asking Buildbucket for the build status
[03:34:11.005] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:369:0) after 1m0s
[03:35:11.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:369:0)
[03:35:11.194] Timer tick, asking Buildbucket for the build status
[03:35:11.430] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:11.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:370:0) after 1m0s
[03:36:11.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:370:0)
[03:36:11.511] Timer tick, asking Buildbucket for the build status
[03:36:11.697] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:11.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:371:0) after 1m0s
[03:37:11.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:371:0)
[03:37:11.783] Timer tick, asking Buildbucket for the build status
[03:37:11.976] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:11.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:372:0) after 1m0s
[03:38:12.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:372:0)
[03:38:12.321] Timer tick, asking Buildbucket for the build status
[03:38:12.432] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:12.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:373:0) after 1m0s
[03:39:12.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:373:0)
[03:39:12.506] Timer tick, asking Buildbucket for the build status
[03:39:12.651] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:12.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:374:0) after 1m0s
[03:40:12.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:374:0)
[03:40:12.801] Timer tick, asking Buildbucket for the build status
[03:40:12.910] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:12.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:375:0) after 1m0s
[03:41:13.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:375:0)
[03:41:13.035] Timer tick, asking Buildbucket for the build status
[03:41:13.207] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:13.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:376:0) after 1m0s
[03:42:13.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:376:0)
[03:42:13.415] Timer tick, asking Buildbucket for the build status
[03:42:13.607] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:13.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:377:0) after 1m0s
[03:43:13.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:377:0)
[03:43:13.604] Timer tick, asking Buildbucket for the build status
[03:43:13.755] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:13.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:378:0) after 1m0s
[03:44:13.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:378:0)
[03:44:13.826] Timer tick, asking Buildbucket for the build status
[03:44:14.054] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:14.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:379:0) after 1m0s
[03:45:14.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:379:0)
[03:45:14.282] Timer tick, asking Buildbucket for the build status
[03:45:14.443] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:14.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:380:0) after 1m0s
[03:46:14.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:380:0)
[03:46:14.600] Timer tick, asking Buildbucket for the build status
[03:46:14.797] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:14.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:381:0) after 1m0s
[03:47:14.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:381:0)
[03:47:14.935] Timer tick, asking Buildbucket for the build status
[03:47:15.083] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:15.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:382:0) after 1m0s
[03:48:15.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:382:0)
[03:48:15.239] Timer tick, asking Buildbucket for the build status
[03:48:15.747] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:15.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:383:0) after 1m0s
[03:49:15.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:383:0)
[03:49:15.779] Timer tick, asking Buildbucket for the build status
[03:49:15.922] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:15.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:384:0) after 1m0s
[03:50:16.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:384:0)
[03:50:16.088] Timer tick, asking Buildbucket for the build status
[03:50:16.351] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:16.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:385:0) after 1m0s
[03:51:16.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:385:0)
[03:51:16.578] Timer tick, asking Buildbucket for the build status
[03:51:16.810] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:16.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:386:0) after 1m0s
[03:52:16.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:386:0)
[03:52:16.869] Timer tick, asking Buildbucket for the build status
[03:52:17.011] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:17.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:387:0) after 1m0s
[03:53:17.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:387:0)
[03:53:17.190] Timer tick, asking Buildbucket for the build status
[03:53:17.290] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:17.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:388:0) after 1m0s
[03:54:17.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:388:0)
[03:54:17.333] Timer tick, asking Buildbucket for the build status
[03:54:17.456] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:17.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:389:0) after 1m0s
[03:55:17.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:389:0)
[03:55:17.514] Timer tick, asking Buildbucket for the build status
[03:55:17.600] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:17.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:390:0) after 1m0s
[03:56:17.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:390:0)
[03:56:17.787] Timer tick, asking Buildbucket for the build status
[03:56:17.915] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:17.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:391:0) after 1m0s
[03:57:17.953] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:391:0)
[03:57:17.953] Timer tick, asking Buildbucket for the build status
[03:57:18.107] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:18.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:392:0) after 1m0s
[03:58:18.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:392:0)
[03:58:18.264] Timer tick, asking Buildbucket for the build status
[03:58:18.356] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:18.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:393:0) after 1m0s
[03:59:18.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:393:0)
[03:59:18.398] Timer tick, asking Buildbucket for the build status
[03:59:18.643] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:18.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:394:0) after 1m0s
[04:00:18.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:394:0)
[04:00:18.737] Timer tick, asking Buildbucket for the build status
[04:00:18.891] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:18.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:395:0) after 1m0s
[04:01:19.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:395:0)
[04:01:19.008] Timer tick, asking Buildbucket for the build status
[04:01:19.185] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:19.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:396:0) after 1m0s
[04:02:19.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:396:0)
[04:02:19.324] Timer tick, asking Buildbucket for the build status
[04:02:19.468] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:19.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:397:0) after 1m0s
[04:03:19.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:397:0)
[04:03:19.658] Timer tick, asking Buildbucket for the build status
[04:03:19.883] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:19.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:398:0) after 1m0s
[04:04:20.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:398:0)
[04:04:20.027] Timer tick, asking Buildbucket for the build status
[04:04:20.427] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:20.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:399:0) after 1m0s
[04:05:20.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:399:0)
[04:05:20.548] Timer tick, asking Buildbucket for the build status
[04:05:21.035] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:21.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:400:0) after 1m0s
[04:06:21.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:400:0)
[04:06:21.198] Timer tick, asking Buildbucket for the build status
[04:06:21.312] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:21.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:401:0) after 1m0s
[04:07:21.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:401:0)
[04:07:21.427] Timer tick, asking Buildbucket for the build status
[04:07:21.595] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:21.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:402:0) after 1m0s
[04:08:21.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:402:0)
[04:08:21.664] Timer tick, asking Buildbucket for the build status
[04:08:22.551] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:22.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:403:0) after 1m0s
[04:09:22.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:403:0)
[04:09:22.705] Timer tick, asking Buildbucket for the build status
[04:09:22.810] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:22.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:404:0) after 1m0s
[04:10:22.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:404:0)
[04:10:22.863] Timer tick, asking Buildbucket for the build status
[04:10:23.052] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:23.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:405:0) after 1m0s
[04:11:23.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:405:0)
[04:11:23.183] Timer tick, asking Buildbucket for the build status
[04:11:23.603] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:23.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:406:0) after 1m0s
[04:12:23.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:406:0)
[04:12:23.978] Timer tick, asking Buildbucket for the build status
[04:12:24.093] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:24.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:407:0) after 1m0s
[04:13:24.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:407:0)
[04:13:24.254] Timer tick, asking Buildbucket for the build status
[04:13:24.382] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:24.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:408:0) after 1m0s
[04:14:24.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:408:0)
[04:14:24.524] Timer tick, asking Buildbucket for the build status
[04:14:24.723] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:24.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:409:0) after 1m0s
[04:15:24.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:409:0)
[04:15:24.864] Timer tick, asking Buildbucket for the build status
[04:15:25.024] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:25.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:410:0) after 1m0s
[04:16:25.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:410:0)
[04:16:25.292] Timer tick, asking Buildbucket for the build status
[04:16:25.410] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:25.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:411:0) after 1m0s
[04:17:25.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:411:0)
[04:17:25.563] Timer tick, asking Buildbucket for the build status
[04:17:25.684] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:25.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:412:0) after 1m0s
[04:18:25.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:412:0)
[04:18:25.803] Timer tick, asking Buildbucket for the build status
[04:18:25.949] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:25.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:413:0) after 1m0s
[04:19:26.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:413:0)
[04:19:26.151] Timer tick, asking Buildbucket for the build status
[04:19:26.799] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:26.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:414:0) after 1m0s
[04:20:26.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:414:0)
[04:20:26.963] Timer tick, asking Buildbucket for the build status
[04:20:27.076] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:27.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:415:0) after 1m0s
[04:21:27.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:415:0)
[04:21:27.205] Timer tick, asking Buildbucket for the build status
[04:21:27.364] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:27.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:416:0) after 1m0s
[04:22:27.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:416:0)
[04:22:27.424] Timer tick, asking Buildbucket for the build status
[04:22:27.535] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:27.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:417:0) after 1m0s
[04:23:27.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:417:0)
[04:23:27.708] Timer tick, asking Buildbucket for the build status
[04:23:27.826] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:27.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:418:0) after 1m0s
[04:24:27.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:418:0)
[04:24:27.926] Timer tick, asking Buildbucket for the build status
[04:24:28.070] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:28.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:419:0) after 1m0s
[04:25:28.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:419:0)
[04:25:28.205] Timer tick, asking Buildbucket for the build status
[04:25:28.336] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:28.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:420:0) after 1m0s
[04:26:28.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:420:0)
[04:26:28.380] Timer tick, asking Buildbucket for the build status
[04:26:28.496] Build 8887266976262905008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:28.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052697040620470976:421:0) after 1m0s
[04:26:57.083] Received PubSub notification, asking Buildbucket for the build status
[04:26:57.459] Build 8887266976262905008: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:26:57.459] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"completed_ts": "1582864016734835",
"created_by": "project:nacl",
"created_ts": "1582838763900185",
"failure_reason": "BUILD_FAILURE",
"id": "8887266976262905008",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@21be4b46c82c14625859b4929af9ef6433fa04a5\", \"title\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/21be4b46c82c14625859b4929af9ef6433fa04a5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@21be4b46c82c14625859b4929af9ef6433fa04a5\", \"title\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/21be4b46c82c14625859b4929af9ef6433fa04a5\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-gg6z\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5140, \"got_revision\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"21be4b46c82c14625859b4929af9ef6433fa04a5\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-gg6z\"], \"image\": [\"chrome-xenial-20022300-4c0e34b5b5e\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4838-b69615e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582854838037240",
"status": "COMPLETED",
"status_changed_ts": "1582854838410157",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5140",
"builder:linux-pnacl-x86_64-tests-x86_32",
"buildset:commit/git/21be4b46c82c14625859b4929af9ef6433fa04a5",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/21be4b46c82c14625859b4929af9ef6433fa04a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052697040620470976",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8887266976262905008/+/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:4aa15c5e4dc74810",
"user_agent:luci-scheduler"
],
"updated_ts": "1582864016800800",
"url": "https://ci.chromium.org/b/8887266976262905008",
"utcnow_ts": "1582864017413546"
}
}
[04:26:57.459] Invocation finished in 7h0m57.098138441s with status FAILED