[20:44:20.386] New invocation is queued and will start shortly
[20:44:21.518] Starting the invocation (attempt 1)
[20:44:21.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:44:21.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:44:21.557] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9094646057494210000",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"55f40a255928a95a71a5c63b37cdf7cc6ec8acae\"}}",
"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:9094646057494210000",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"user_agent:luci-scheduler",
"buildset:commit/git/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"gitiles_ref:refs/heads/master"
]
}
[20:44:24.032] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542833061672130",
"experimental": true,
"id": "8929215995482730480",
"lease_expiration_ts": "1542951863435908",
"lease_key": "1976923069",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"55f40a255928a95a71a5c63b37cdf7cc6ec8acae\"}}",
"project": "nacl",
"result_details_json": "null",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1542833063935923",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/9",
"builder:linux-pnacl-x86_64-tests-x86_32",
"buildset:commit/git/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9094646057494210000",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_dimension:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.nacl.toolchain",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/9",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8929215995482730480",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:586d78b1df9b356be85f0824ec8364d9ecbabf90",
"swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
"swarming_tag:buildset:commit/git/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"swarming_tag:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8929215995482730480/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.nacl.toolchain",
"swarming_tag:priority:60",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9094646057494210000",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:18bb9400d243d9bda9184c768cca1f92d5a22697",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4150d5cb40288810",
"user_agent:luci-scheduler"
],
"updated_ts": "1542833063936077",
"url": "https://ci.chromium.org/b/8929215995482730480",
"utcnow_ts": "1542833064017212"
}
}
[20:44:24.032] Task URL: https://ci.chromium.org/b/8929215995482730480
[20:44:24.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:2:0) after 1m0s
[20:45:24.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:2:0)
[20:45:24.107] Timer tick, asking Buildbucket for the build status
[20:45:24.166] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:24.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:3:0) after 1m0s
[20:46:24.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:3:0)
[20:46:24.185] Timer tick, asking Buildbucket for the build status
[20:46:24.269] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:24.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:4:0) after 1m0s
[20:47:24.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:4:0)
[20:47:24.682] Timer tick, asking Buildbucket for the build status
[20:47:24.758] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:24.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:5:0) after 1m0s
[20:48:24.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:5:0)
[20:48:24.800] Timer tick, asking Buildbucket for the build status
[20:48:24.864] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:24.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:6:0) after 1m0s
[20:49:24.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:6:0)
[20:49:24.908] Timer tick, asking Buildbucket for the build status
[20:49:24.992] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:24.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:7:0) after 1m0s
[20:50:25.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:7:0)
[20:50:25.077] Timer tick, asking Buildbucket for the build status
[20:50:25.141] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:25.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:8:0) after 1m0s
[20:51:25.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:8:0)
[20:51:25.182] Timer tick, asking Buildbucket for the build status
[20:51:25.477] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:25.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:9:0) after 1m0s
[20:52:25.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:9:0)
[20:52:25.498] Timer tick, asking Buildbucket for the build status
[20:52:25.578] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:25.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:10:0) after 1m0s
[20:53:25.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:10:0)
[20:53:25.614] Timer tick, asking Buildbucket for the build status
[20:53:25.886] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:25.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:11:0) after 1m0s
[20:54:25.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:11:0)
[20:54:25.910] Timer tick, asking Buildbucket for the build status
[20:54:25.968] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:25.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:12:0) after 1m0s
[20:55:25.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:12:0)
[20:55:25.986] Timer tick, asking Buildbucket for the build status
[20:55:26.056] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:26.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:13:0) after 1m0s
[20:56:26.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:13:0)
[20:56:26.087] Timer tick, asking Buildbucket for the build status
[20:56:26.138] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:26.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:14:0) after 1m0s
[20:57:26.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:14:0)
[20:57:26.192] Timer tick, asking Buildbucket for the build status
[20:57:26.276] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:26.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:15:0) after 1m0s
[20:58:26.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:15:0)
[20:58:26.294] Timer tick, asking Buildbucket for the build status
[20:58:26.377] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:26.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:16:0) after 1m0s
[20:59:26.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:16:0)
[20:59:26.404] Timer tick, asking Buildbucket for the build status
[20:59:26.494] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:26.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:17:0) after 1m0s
[21:00:26.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:17:0)
[21:00:26.515] Timer tick, asking Buildbucket for the build status
[21:00:26.550] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:26.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:18:0) after 1m0s
[21:01:26.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:18:0)
[21:01:26.570] Timer tick, asking Buildbucket for the build status
[21:01:26.633] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:26.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:19:0) after 1m0s
[21:02:26.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:19:0)
[21:02:26.653] Timer tick, asking Buildbucket for the build status
[21:02:26.687] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:26.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:20:0) after 1m0s
[21:03:26.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:20:0)
[21:03:26.706] Timer tick, asking Buildbucket for the build status
[21:03:26.767] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:26.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:21:0) after 1m0s
[21:04:26.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:21:0)
[21:04:26.794] Timer tick, asking Buildbucket for the build status
[21:04:26.832] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:26.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:22:0) after 1m0s
[21:05:26.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:22:0)
[21:05:26.849] Timer tick, asking Buildbucket for the build status
[21:05:26.916] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:26.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:23:0) after 1m0s
[21:06:26.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:23:0)
[21:06:26.969] Timer tick, asking Buildbucket for the build status
[21:06:27.022] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:27.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:24:0) after 1m0s
[21:07:27.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:24:0)
[21:07:27.067] Timer tick, asking Buildbucket for the build status
[21:07:27.141] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:27.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:25:0) after 1m0s
[21:08:27.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:25:0)
[21:08:27.174] Timer tick, asking Buildbucket for the build status
[21:08:27.214] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:27.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:26:0) after 1m0s
[21:09:27.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:26:0)
[21:09:27.242] Timer tick, asking Buildbucket for the build status
[21:09:27.298] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:27.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:27:0) after 1m0s
[21:10:27.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:27:0)
[21:10:27.318] Timer tick, asking Buildbucket for the build status
[21:10:27.388] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:27.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:28:0) after 1m0s
[21:11:27.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:28:0)
[21:11:27.408] Timer tick, asking Buildbucket for the build status
[21:11:27.503] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:27.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:29:0) after 1m0s
[21:12:27.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:29:0)
[21:12:27.532] Timer tick, asking Buildbucket for the build status
[21:12:27.571] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:27.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:30:0) after 1m0s
[21:13:27.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:30:0)
[21:13:27.592] Timer tick, asking Buildbucket for the build status
[21:13:27.629] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:27.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:31:0) after 1m0s
[21:14:27.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:31:0)
[21:14:27.664] Timer tick, asking Buildbucket for the build status
[21:14:27.720] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:27.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:32:0) after 1m0s
[21:15:27.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:32:0)
[21:15:27.749] Timer tick, asking Buildbucket for the build status
[21:15:27.811] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:27.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:33:0) after 1m0s
[21:16:27.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:33:0)
[21:16:27.847] Timer tick, asking Buildbucket for the build status
[21:16:27.939] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:27.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:34:0) after 1m0s
[21:17:27.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:34:0)
[21:17:27.962] Timer tick, asking Buildbucket for the build status
[21:17:28.147] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:28.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:35:0) after 1m0s
[21:18:28.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:35:0)
[21:18:28.166] Timer tick, asking Buildbucket for the build status
[21:18:28.260] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:28.260] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:36:0) after 1m0s
[21:19:28.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:36:0)
[21:19:28.282] Timer tick, asking Buildbucket for the build status
[21:19:28.325] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:28.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:37:0) after 1m0s
[21:20:28.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:37:0)
[21:20:28.348] Timer tick, asking Buildbucket for the build status
[21:20:28.524] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:28.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:38:0) after 1m0s
[21:21:28.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:38:0)
[21:21:28.602] Timer tick, asking Buildbucket for the build status
[21:21:28.700] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:28.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:39:0) after 1m0s
[21:22:28.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:39:0)
[21:22:28.727] Timer tick, asking Buildbucket for the build status
[21:22:28.832] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:28.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:40:0) after 1m0s
[21:23:28.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:40:0)
[21:23:28.852] Timer tick, asking Buildbucket for the build status
[21:23:28.925] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:28.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:41:0) after 1m0s
[21:24:28.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:41:0)
[21:24:28.944] Timer tick, asking Buildbucket for the build status
[21:24:29.014] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:29.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:42:0) after 1m0s
[21:25:29.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:42:0)
[21:25:29.034] Timer tick, asking Buildbucket for the build status
[21:25:29.095] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:29.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:43:0) after 1m0s
[21:26:29.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:43:0)
[21:26:29.124] Timer tick, asking Buildbucket for the build status
[21:26:29.170] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:29.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:44:0) after 1m0s
[21:27:29.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:44:0)
[21:27:29.244] Timer tick, asking Buildbucket for the build status
[21:27:29.307] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:29.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:45:0) after 1m0s
[21:28:29.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:45:0)
[21:28:29.338] Timer tick, asking Buildbucket for the build status
[21:28:29.378] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:29.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:46:0) after 1m0s
[21:29:29.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:46:0)
[21:29:29.397] Timer tick, asking Buildbucket for the build status
[21:29:29.552] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:29.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:47:0) after 1m0s
[21:30:29.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:47:0)
[21:30:29.608] Timer tick, asking Buildbucket for the build status
[21:30:29.647] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:29.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:48:0) after 1m0s
[21:31:29.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:48:0)
[21:31:29.670] Timer tick, asking Buildbucket for the build status
[21:31:29.750] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:29.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:49:0) after 1m0s
[21:32:29.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:49:0)
[21:32:29.770] Timer tick, asking Buildbucket for the build status
[21:32:29.843] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:29.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:50:0) after 1m0s
[21:33:29.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:50:0)
[21:33:29.861] Timer tick, asking Buildbucket for the build status
[21:33:29.900] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:29.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:51:0) after 1m0s
[21:34:29.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:51:0)
[21:34:29.921] Timer tick, asking Buildbucket for the build status
[21:34:29.964] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:29.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:52:0) after 1m0s
[21:35:29.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:52:0)
[21:35:29.985] Timer tick, asking Buildbucket for the build status
[21:35:30.080] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:30.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:53:0) after 1m0s
[21:36:30.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:53:0)
[21:36:30.119] Timer tick, asking Buildbucket for the build status
[21:36:30.161] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:30.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:54:0) after 1m0s
[21:37:30.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:54:0)
[21:37:30.183] Timer tick, asking Buildbucket for the build status
[21:37:30.230] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:30.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:55:0) after 1m0s
[21:38:30.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:55:0)
[21:38:30.253] Timer tick, asking Buildbucket for the build status
[21:38:30.316] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:30.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:56:0) after 1m0s
[21:39:30.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:56:0)
[21:39:30.340] Timer tick, asking Buildbucket for the build status
[21:39:30.390] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:30.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:57:0) after 1m0s
[21:40:30.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:57:0)
[21:40:30.420] Timer tick, asking Buildbucket for the build status
[21:40:30.464] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:30.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:58:0) after 1m0s
[21:41:30.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:58:0)
[21:41:30.505] Timer tick, asking Buildbucket for the build status
[21:41:30.608] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:30.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:59:0) after 1m0s
[21:42:30.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:59:0)
[21:42:30.631] Timer tick, asking Buildbucket for the build status
[21:42:30.677] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:30.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:60:0) after 1m0s
[21:43:30.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:60:0)
[21:43:30.728] Timer tick, asking Buildbucket for the build status
[21:43:30.882] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:30.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:61:0) after 1m0s
[21:44:30.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:61:0)
[21:44:30.909] Timer tick, asking Buildbucket for the build status
[21:44:30.947] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:30.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:62:0) after 1m0s
[21:45:30.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:62:0)
[21:45:30.971] Timer tick, asking Buildbucket for the build status
[21:45:31.046] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:31.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:63:0) after 1m0s
[21:46:31.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:63:0)
[21:46:31.140] Timer tick, asking Buildbucket for the build status
[21:46:31.176] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:31.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:64:0) after 1m0s
[21:47:31.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:64:0)
[21:47:31.210] Timer tick, asking Buildbucket for the build status
[21:47:31.489] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:31.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:65:0) after 1m0s
[21:48:31.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:65:0)
[21:48:31.513] Timer tick, asking Buildbucket for the build status
[21:48:31.546] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:31.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:66:0) after 1m0s
[21:49:31.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:66:0)
[21:49:31.569] Timer tick, asking Buildbucket for the build status
[21:49:31.615] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:31.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:67:0) after 1m0s
[21:50:31.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:67:0)
[21:50:31.637] Timer tick, asking Buildbucket for the build status
[21:50:31.711] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:31.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:68:0) after 1m0s
[21:51:31.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:68:0)
[21:51:31.739] Timer tick, asking Buildbucket for the build status
[21:51:31.797] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:31.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:69:0) after 1m0s
[21:52:31.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:69:0)
[21:52:31.824] Timer tick, asking Buildbucket for the build status
[21:52:31.866] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:31.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:70:0) after 1m0s
[21:53:31.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:70:0)
[21:53:31.903] Timer tick, asking Buildbucket for the build status
[21:53:31.965] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:31.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:71:0) after 1m0s
[21:54:31.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:71:0)
[21:54:31.992] Timer tick, asking Buildbucket for the build status
[21:54:32.054] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:32.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:72:0) after 1m0s
[21:55:32.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:72:0)
[21:55:32.078] Timer tick, asking Buildbucket for the build status
[21:55:32.139] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:32.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:73:0) after 1m0s
[21:56:32.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:73:0)
[21:56:32.193] Timer tick, asking Buildbucket for the build status
[21:56:32.237] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:32.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:74:0) after 1m0s
[21:57:32.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:74:0)
[21:57:32.261] Timer tick, asking Buildbucket for the build status
[21:57:32.314] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:32.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:75:0) after 1m0s
[21:58:32.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:75:0)
[21:58:32.334] Timer tick, asking Buildbucket for the build status
[21:58:32.506] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:32.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:76:0) after 1m0s
[21:59:32.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:76:0)
[21:59:32.530] Timer tick, asking Buildbucket for the build status
[21:59:32.589] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:32.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:77:0) after 1m0s
[22:00:32.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:77:0)
[22:00:32.614] Timer tick, asking Buildbucket for the build status
[22:00:32.687] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:32.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:78:0) after 1m0s
[22:01:32.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:78:0)
[22:01:32.707] Timer tick, asking Buildbucket for the build status
[22:01:32.804] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:32.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:79:0) after 1m0s
[22:02:32.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:79:0)
[22:02:32.825] Timer tick, asking Buildbucket for the build status
[22:02:32.891] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:32.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:80:0) after 1m0s
[22:03:32.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:80:0)
[22:03:32.918] Timer tick, asking Buildbucket for the build status
[22:03:33.003] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:33.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:81:0) after 1m0s
[22:04:33.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:81:0)
[22:04:33.076] Timer tick, asking Buildbucket for the build status
[22:04:33.182] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:33.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:82:0) after 1m0s
[22:05:33.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:82:0)
[22:05:33.205] Timer tick, asking Buildbucket for the build status
[22:05:33.303] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:33.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:83:0) after 1m0s
[22:06:33.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:83:0)
[22:06:33.425] Timer tick, asking Buildbucket for the build status
[22:06:33.466] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:33.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:84:0) after 1m0s
[22:07:33.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:84:0)
[22:07:33.605] Timer tick, asking Buildbucket for the build status
[22:07:33.701] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:33.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:85:0) after 1m0s
[22:08:33.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:85:0)
[22:08:33.745] Timer tick, asking Buildbucket for the build status
[22:08:33.819] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:33.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:86:0) after 1m0s
[22:09:33.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:86:0)
[22:09:33.840] Timer tick, asking Buildbucket for the build status
[22:09:33.948] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:33.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:87:0) after 1m0s
[22:10:33.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:87:0)
[22:10:33.974] Timer tick, asking Buildbucket for the build status
[22:10:34.097] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:34.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:88:0) after 1m0s
[22:11:34.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:88:0)
[22:11:34.145] Timer tick, asking Buildbucket for the build status
[22:11:34.226] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:34.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:89:0) after 1m0s
[22:12:34.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:89:0)
[22:12:34.291] Timer tick, asking Buildbucket for the build status
[22:12:34.368] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:34.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:90:0) after 1m0s
[22:13:34.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:90:0)
[22:13:34.397] Timer tick, asking Buildbucket for the build status
[22:13:34.523] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:34.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:91:0) after 1m0s
[22:14:34.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:91:0)
[22:14:34.541] Timer tick, asking Buildbucket for the build status
[22:14:34.581] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:34.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:92:0) after 1m0s
[22:15:34.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:92:0)
[22:15:34.604] Timer tick, asking Buildbucket for the build status
[22:15:34.702] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:34.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:93:0) after 1m0s
[22:16:34.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:93:0)
[22:16:34.730] Timer tick, asking Buildbucket for the build status
[22:16:34.807] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:34.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:94:0) after 1m0s
[22:17:34.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:94:0)
[22:17:34.833] Timer tick, asking Buildbucket for the build status
[22:17:34.912] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:34.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:95:0) after 1m0s
[22:18:34.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:95:0)
[22:18:34.936] Timer tick, asking Buildbucket for the build status
[22:18:35.032] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:35.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:96:0) after 1m0s
[22:19:35.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:96:0)
[22:19:35.093] Timer tick, asking Buildbucket for the build status
[22:19:35.176] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:35.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:97:0) after 1m0s
[22:20:35.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:97:0)
[22:20:35.202] Timer tick, asking Buildbucket for the build status
[22:20:35.250] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:35.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:98:0) after 1m0s
[22:21:35.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:98:0)
[22:21:35.278] Timer tick, asking Buildbucket for the build status
[22:21:35.411] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:35.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:99:0) after 1m0s
[22:22:35.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:99:0)
[22:22:35.458] Timer tick, asking Buildbucket for the build status
[22:22:35.623] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:35.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:100:0) after 1m0s
[22:23:35.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:100:0)
[22:23:35.646] Timer tick, asking Buildbucket for the build status
[22:23:35.974] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:35.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:101:0) after 1m0s
[22:24:35.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:101:0)
[22:24:35.998] Timer tick, asking Buildbucket for the build status
[22:24:36.037] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:36.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:102:0) after 1m0s
[22:25:36.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:102:0)
[22:25:36.056] Timer tick, asking Buildbucket for the build status
[22:25:36.193] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:36.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:103:0) after 1m0s
[22:26:36.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:103:0)
[22:26:36.241] Timer tick, asking Buildbucket for the build status
[22:26:36.295] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:36.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:104:0) after 1m0s
[22:27:36.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:104:0)
[22:27:36.316] Timer tick, asking Buildbucket for the build status
[22:27:36.420] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:36.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:105:0) after 1m0s
[22:28:36.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:105:0)
[22:28:36.443] Timer tick, asking Buildbucket for the build status
[22:28:36.489] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:36.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:106:0) after 1m0s
[22:29:36.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:106:0)
[22:29:36.511] Timer tick, asking Buildbucket for the build status
[22:29:36.615] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:36.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:107:0) after 1m0s
[22:30:36.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:107:0)
[22:30:36.645] Timer tick, asking Buildbucket for the build status
[22:30:36.737] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:36.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:108:0) after 1m0s
[22:31:36.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:108:0)
[22:31:36.762] Timer tick, asking Buildbucket for the build status
[22:31:36.834] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:36.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:109:0) after 1m0s
[22:32:36.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:109:0)
[22:32:36.881] Timer tick, asking Buildbucket for the build status
[22:32:37.101] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:37.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:110:0) after 1m0s
[22:33:37.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:110:0)
[22:33:37.124] Timer tick, asking Buildbucket for the build status
[22:33:37.331] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:37.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:111:0) after 1m0s
[22:34:37.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:111:0)
[22:34:37.362] Timer tick, asking Buildbucket for the build status
[22:34:37.430] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:37.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:112:0) after 1m0s
[22:35:37.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:112:0)
[22:35:37.508] Timer tick, asking Buildbucket for the build status
[22:35:37.576] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:37.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:113:0) after 1m0s
[22:36:37.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:113:0)
[22:36:37.664] Timer tick, asking Buildbucket for the build status
[22:36:37.718] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:37.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:114:0) after 1m0s
[22:37:37.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:114:0)
[22:37:37.848] Timer tick, asking Buildbucket for the build status
[22:37:38.040] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:38.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:115:0) after 1m0s
[22:38:38.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:115:0)
[22:38:38.062] Timer tick, asking Buildbucket for the build status
[22:38:38.103] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:38.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:116:0) after 1m0s
[22:39:38.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:116:0)
[22:39:38.150] Timer tick, asking Buildbucket for the build status
[22:39:38.350] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:38.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:117:0) after 1m0s
[22:40:38.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:117:0)
[22:40:38.418] Timer tick, asking Buildbucket for the build status
[22:40:38.457] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:38.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:118:0) after 1m0s
[22:41:38.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:118:0)
[22:41:38.484] Timer tick, asking Buildbucket for the build status
[22:41:38.539] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:38.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:119:0) after 1m0s
[22:42:38.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:119:0)
[22:42:38.565] Timer tick, asking Buildbucket for the build status
[22:42:38.733] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:38.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:120:0) after 1m0s
[22:43:38.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:120:0)
[22:43:38.811] Timer tick, asking Buildbucket for the build status
[22:43:38.868] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:38.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:121:0) after 1m0s
[22:44:38.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:121:0)
[22:44:38.893] Timer tick, asking Buildbucket for the build status
[22:44:39.069] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:39.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:122:0) after 1m0s
[22:45:39.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:122:0)
[22:45:39.138] Timer tick, asking Buildbucket for the build status
[22:45:39.216] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:39.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:123:0) after 1m0s
[22:46:39.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:123:0)
[22:46:39.238] Timer tick, asking Buildbucket for the build status
[22:46:39.299] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:39.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:124:0) after 1m0s
[22:47:39.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:124:0)
[22:47:39.328] Timer tick, asking Buildbucket for the build status
[22:47:39.402] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:39.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:125:0) after 1m0s
[22:48:39.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:125:0)
[22:48:39.429] Timer tick, asking Buildbucket for the build status
[22:48:39.471] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:39.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:126:0) after 1m0s
[22:49:39.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:126:0)
[22:49:39.517] Timer tick, asking Buildbucket for the build status
[22:49:39.588] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:39.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:127:0) after 1m0s
[22:50:39.630] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:127:0)
[22:50:39.630] Timer tick, asking Buildbucket for the build status
[22:50:39.731] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:39.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:128:0) after 1m0s
[22:51:39.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:128:0)
[22:51:39.758] Timer tick, asking Buildbucket for the build status
[22:51:39.944] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:39.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:129:0) after 1m0s
[22:52:40.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:129:0)
[22:52:40.005] Timer tick, asking Buildbucket for the build status
[22:52:40.099] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:40.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:130:0) after 1m0s
[22:53:40.123] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:130:0)
[22:53:40.123] Timer tick, asking Buildbucket for the build status
[22:53:40.188] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:40.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:131:0) after 1m0s
[22:54:40.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:131:0)
[22:54:40.278] Timer tick, asking Buildbucket for the build status
[22:54:40.323] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:40.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:132:0) after 1m0s
[22:55:40.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:132:0)
[22:55:40.383] Timer tick, asking Buildbucket for the build status
[22:55:40.482] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:40.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:133:0) after 1m0s
[22:56:40.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:133:0)
[22:56:40.515] Timer tick, asking Buildbucket for the build status
[22:56:40.731] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:40.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:134:0) after 1m0s
[22:57:41.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:134:0)
[22:57:41.112] Timer tick, asking Buildbucket for the build status
[22:57:41.246] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:41.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:135:0) after 1m0s
[22:58:41.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:135:0)
[22:58:41.280] Timer tick, asking Buildbucket for the build status
[22:58:41.381] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:41.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:136:0) after 1m0s
[22:59:41.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:136:0)
[22:59:41.695] Timer tick, asking Buildbucket for the build status
[22:59:41.855] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:41.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:137:0) after 1m0s
[23:00:42.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:137:0)
[23:00:42.067] Timer tick, asking Buildbucket for the build status
[23:00:42.311] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:42.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:138:0) after 1m0s
[23:01:42.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:138:0)
[23:01:42.572] Timer tick, asking Buildbucket for the build status
[23:01:42.824] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:42.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:139:0) after 1m0s
[23:02:42.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:139:0)
[23:02:42.889] Timer tick, asking Buildbucket for the build status
[23:02:43.018] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:43.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:140:0) after 1m0s
[23:03:43.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:140:0)
[23:03:43.042] Timer tick, asking Buildbucket for the build status
[23:03:43.185] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:43.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:141:0) after 1m0s
[23:04:43.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:141:0)
[23:04:43.294] Timer tick, asking Buildbucket for the build status
[23:04:43.330] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:43.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:142:0) after 1m0s
[23:05:43.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:142:0)
[23:05:43.613] Timer tick, asking Buildbucket for the build status
[23:05:43.685] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:43.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:143:0) after 1m0s
[23:06:43.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:143:0)
[23:06:43.714] Timer tick, asking Buildbucket for the build status
[23:06:43.842] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:43.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:144:0) after 1m0s
[23:07:43.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:144:0)
[23:07:43.880] Timer tick, asking Buildbucket for the build status
[23:07:43.951] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:43.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:145:0) after 1m0s
[23:08:44.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:145:0)
[23:08:44.224] Timer tick, asking Buildbucket for the build status
[23:08:44.268] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:44.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:146:0) after 1m0s
[23:09:44.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:146:0)
[23:09:44.679] Timer tick, asking Buildbucket for the build status
[23:09:44.773] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:44.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:147:0) after 1m0s
[23:10:44.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:147:0)
[23:10:44.874] Timer tick, asking Buildbucket for the build status
[23:10:44.933] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:44.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:148:0) after 1m0s
[23:11:45.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:148:0)
[23:11:45.087] Timer tick, asking Buildbucket for the build status
[23:11:45.196] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:45.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:149:0) after 1m0s
[23:12:45.233] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:149:0)
[23:12:45.233] Timer tick, asking Buildbucket for the build status
[23:12:45.282] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:45.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:150:0) after 1m0s
[23:13:45.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:150:0)
[23:13:45.410] Timer tick, asking Buildbucket for the build status
[23:13:45.488] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:45.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:151:0) after 1m0s
[23:14:45.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:151:0)
[23:14:45.516] Timer tick, asking Buildbucket for the build status
[23:14:45.575] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:45.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:152:0) after 1m0s
[23:15:45.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:152:0)
[23:15:45.684] Timer tick, asking Buildbucket for the build status
[23:15:45.786] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:45.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:153:0) after 1m0s
[23:16:45.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:153:0)
[23:16:45.887] Timer tick, asking Buildbucket for the build status
[23:16:45.969] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:45.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:154:0) after 1m0s
[23:17:46.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:154:0)
[23:17:46.007] Timer tick, asking Buildbucket for the build status
[23:17:46.315] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:46.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:155:0) after 1m0s
[23:18:46.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:155:0)
[23:18:46.375] Timer tick, asking Buildbucket for the build status
[23:18:46.468] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:46.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:156:0) after 1m0s
[23:19:46.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:156:0)
[23:19:46.508] Timer tick, asking Buildbucket for the build status
[23:19:46.679] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:46.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:157:0) after 1m0s
[23:20:46.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:157:0)
[23:20:46.714] Timer tick, asking Buildbucket for the build status
[23:20:46.841] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:46.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:158:0) after 1m0s
[23:21:46.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:158:0)
[23:21:46.983] Timer tick, asking Buildbucket for the build status
[23:21:47.111] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:47.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:159:0) after 1m0s
[23:22:47.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:159:0)
[23:22:47.141] Timer tick, asking Buildbucket for the build status
[23:22:47.207] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:47.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:160:0) after 1m0s
[23:23:47.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:160:0)
[23:23:47.256] Timer tick, asking Buildbucket for the build status
[23:23:47.331] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:47.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:161:0) after 1m0s
[23:24:47.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:161:0)
[23:24:47.434] Timer tick, asking Buildbucket for the build status
[23:24:47.471] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:47.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:162:0) after 1m0s
[23:25:47.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:162:0)
[23:25:47.509] Timer tick, asking Buildbucket for the build status
[23:25:47.567] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:47.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:163:0) after 1m0s
[23:26:47.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:163:0)
[23:26:47.595] Timer tick, asking Buildbucket for the build status
[23:26:47.685] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:47.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:164:0) after 1m0s
[23:27:47.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:164:0)
[23:27:47.712] Timer tick, asking Buildbucket for the build status
[23:27:48.003] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:48.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:165:0) after 1m0s
[23:28:48.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:165:0)
[23:28:48.033] Timer tick, asking Buildbucket for the build status
[23:28:48.140] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:48.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:166:0) after 1m0s
[23:29:48.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:166:0)
[23:29:48.281] Timer tick, asking Buildbucket for the build status
[23:29:48.407] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:48.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:167:0) after 1m0s
[23:30:48.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:167:0)
[23:30:48.495] Timer tick, asking Buildbucket for the build status
[23:30:48.667] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:48.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:168:0) after 1m0s
[23:31:48.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:168:0)
[23:31:48.701] Timer tick, asking Buildbucket for the build status
[23:31:48.881] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:48.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:169:0) after 1m0s
[23:32:48.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:169:0)
[23:32:48.907] Timer tick, asking Buildbucket for the build status
[23:32:48.965] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:48.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:170:0) after 1m0s
[23:33:49.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:170:0)
[23:33:49.049] Timer tick, asking Buildbucket for the build status
[23:33:49.113] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:49.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:171:0) after 1m0s
[23:34:49.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:171:0)
[23:34:49.143] Timer tick, asking Buildbucket for the build status
[23:34:49.217] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:49.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:172:0) after 1m0s
[23:35:49.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:172:0)
[23:35:49.245] Timer tick, asking Buildbucket for the build status
[23:35:49.319] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:49.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:173:0) after 1m0s
[23:36:49.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:173:0)
[23:36:49.344] Timer tick, asking Buildbucket for the build status
[23:36:49.477] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:49.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:174:0) after 1m0s
[23:37:49.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:174:0)
[23:37:49.513] Timer tick, asking Buildbucket for the build status
[23:37:49.574] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:49.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:175:0) after 1m0s
[23:38:49.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:175:0)
[23:38:49.602] Timer tick, asking Buildbucket for the build status
[23:38:49.644] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:49.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:176:0) after 1m0s
[23:39:49.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:176:0)
[23:39:49.670] Timer tick, asking Buildbucket for the build status
[23:39:49.746] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:49.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:177:0) after 1m0s
[23:40:49.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:177:0)
[23:40:49.776] Timer tick, asking Buildbucket for the build status
[23:40:49.835] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:49.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:178:0) after 1m0s
[23:41:49.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:178:0)
[23:41:49.859] Timer tick, asking Buildbucket for the build status
[23:41:50.054] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:50.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:179:0) after 1m0s
[23:42:50.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:179:0)
[23:42:50.109] Timer tick, asking Buildbucket for the build status
[23:42:50.153] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:50.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:180:0) after 1m0s
[23:43:50.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:180:0)
[23:43:50.176] Timer tick, asking Buildbucket for the build status
[23:43:50.255] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:50.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:181:0) after 1m0s
[23:44:50.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:181:0)
[23:44:50.285] Timer tick, asking Buildbucket for the build status
[23:44:50.368] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:50.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:182:0) after 1m0s
[23:45:50.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:182:0)
[23:45:50.412] Timer tick, asking Buildbucket for the build status
[23:45:50.484] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:50.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:183:0) after 1m0s
[23:46:50.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:183:0)
[23:46:50.513] Timer tick, asking Buildbucket for the build status
[23:46:50.611] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:50.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:184:0) after 1m0s
[23:47:50.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:184:0)
[23:47:50.638] Timer tick, asking Buildbucket for the build status
[23:47:50.703] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:50.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:185:0) after 1m0s
[23:48:50.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:185:0)
[23:48:50.850] Timer tick, asking Buildbucket for the build status
[23:48:50.908] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:50.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:186:0) after 1m0s
[23:49:51.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:186:0)
[23:49:51.165] Timer tick, asking Buildbucket for the build status
[23:49:51.268] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:51.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:187:0) after 1m0s
[23:50:51.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:187:0)
[23:50:51.580] Timer tick, asking Buildbucket for the build status
[23:50:51.629] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:51.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:188:0) after 1m0s
[23:51:51.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:188:0)
[23:51:51.684] Timer tick, asking Buildbucket for the build status
[23:51:51.787] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:51.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:189:0) after 1m0s
[23:52:52.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:189:0)
[23:52:52.216] Timer tick, asking Buildbucket for the build status
[23:52:52.299] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:52.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:190:0) after 1m0s
[23:53:52.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:190:0)
[23:53:52.343] Timer tick, asking Buildbucket for the build status
[23:53:52.414] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:52.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:191:0) after 1m0s
[23:54:52.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:191:0)
[23:54:52.437] Timer tick, asking Buildbucket for the build status
[23:54:52.564] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:52.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:192:0) after 1m0s
[23:55:52.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:192:0)
[23:55:52.663] Timer tick, asking Buildbucket for the build status
[23:55:52.816] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:52.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:193:0) after 1m0s
[23:56:52.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:193:0)
[23:56:52.881] Timer tick, asking Buildbucket for the build status
[23:56:52.976] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:52.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:194:0) after 1m0s
[23:57:53.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:194:0)
[23:57:53.378] Timer tick, asking Buildbucket for the build status
[23:57:53.449] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:53.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:195:0) after 1m0s
[23:58:53.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:195:0)
[23:58:53.958] Timer tick, asking Buildbucket for the build status
[23:58:54.005] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:54.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:196:0) after 1m0s
[23:59:54.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:196:0)
[23:59:54.579] Timer tick, asking Buildbucket for the build status
[23:59:54.685] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:54.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:197:0) after 1m0s
[00:00:55.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:197:0)
[00:00:55.168] Timer tick, asking Buildbucket for the build status
[00:00:55.218] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:55.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:198:0) after 1m0s
[00:01:55.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:198:0)
[00:01:55.844] Timer tick, asking Buildbucket for the build status
[00:01:56.073] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:56.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:199:0) after 1m0s
[00:02:56.480] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:199:0)
[00:02:56.480] Timer tick, asking Buildbucket for the build status
[00:02:56.617] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:56.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:200:0) after 1m0s
[00:03:57.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:200:0)
[00:03:57.023] Timer tick, asking Buildbucket for the build status
[00:03:57.132] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:57.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:201:0) after 1m0s
[00:04:57.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:201:0)
[00:04:57.627] Timer tick, asking Buildbucket for the build status
[00:04:57.674] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:57.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:202:0) after 1m0s
[00:05:57.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:202:0)
[00:05:57.714] Timer tick, asking Buildbucket for the build status
[00:05:57.851] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:57.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:203:0) after 1m0s
[00:06:57.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:203:0)
[00:06:57.881] Timer tick, asking Buildbucket for the build status
[00:06:57.960] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:57.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:204:0) after 1m0s
[00:07:58.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:204:0)
[00:07:58.034] Timer tick, asking Buildbucket for the build status
[00:07:58.304] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:58.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:205:0) after 1m0s
[00:08:58.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:205:0)
[00:08:58.332] Timer tick, asking Buildbucket for the build status
[00:08:58.398] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:58.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:206:0) after 1m0s
[00:09:58.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:206:0)
[00:09:58.477] Timer tick, asking Buildbucket for the build status
[00:09:58.691] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:58.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:207:0) after 1m0s
[00:10:58.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:207:0)
[00:10:58.721] Timer tick, asking Buildbucket for the build status
[00:10:58.758] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:58.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:208:0) after 1m0s
[00:11:58.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:208:0)
[00:11:58.785] Timer tick, asking Buildbucket for the build status
[00:11:58.847] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:58.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:209:0) after 1m0s
[00:12:58.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:209:0)
[00:12:58.874] Timer tick, asking Buildbucket for the build status
[00:12:58.930] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:58.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:210:0) after 1m0s
[00:13:58.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:210:0)
[00:13:58.958] Timer tick, asking Buildbucket for the build status
[00:13:59.112] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:59.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:211:0) after 1m0s
[00:14:59.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:211:0)
[00:14:59.141] Timer tick, asking Buildbucket for the build status
[00:14:59.232] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:59.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:212:0) after 1m0s
[00:15:59.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:212:0)
[00:15:59.363] Timer tick, asking Buildbucket for the build status
[00:15:59.440] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:59.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:213:0) after 1m0s
[00:16:59.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:213:0)
[00:16:59.597] Timer tick, asking Buildbucket for the build status
[00:16:59.696] Build 8929215995482730480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:59.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:214:0) after 1m0s
[00:17:26.398] Received PubSub notification, asking Buildbucket for the build status
[00:17:26.491] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:00.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:214:0)
[00:18:00.096] Timer tick, asking Buildbucket for the build status
[00:18:00.188] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:00.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:216:0) after 1m0s
[00:19:00.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:216:0)
[00:19:00.219] Timer tick, asking Buildbucket for the build status
[00:19:00.322] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:00.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:217:0) after 1m0s
[00:20:00.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:217:0)
[00:20:00.350] Timer tick, asking Buildbucket for the build status
[00:20:00.471] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:00.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:218:0) after 1m0s
[00:21:00.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:218:0)
[00:21:00.615] Timer tick, asking Buildbucket for the build status
[00:21:00.698] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:00.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:219:0) after 1m0s
[00:22:00.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:219:0)
[00:22:00.719] Timer tick, asking Buildbucket for the build status
[00:22:00.808] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:00.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:220:0) after 1m0s
[00:23:00.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:220:0)
[00:23:00.832] Timer tick, asking Buildbucket for the build status
[00:23:01.030] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:01.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:221:0) after 1m0s
[00:24:01.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:221:0)
[00:24:01.061] Timer tick, asking Buildbucket for the build status
[00:24:01.149] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:01.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:222:0) after 1m0s
[00:25:01.170] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:222:0)
[00:25:01.170] Timer tick, asking Buildbucket for the build status
[00:25:01.269] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:01.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:223:0) after 1m0s
[00:26:01.300] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:223:0)
[00:26:01.300] Timer tick, asking Buildbucket for the build status
[00:26:01.375] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:01.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:224:0) after 1m0s
[00:27:01.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:224:0)
[00:27:01.408] Timer tick, asking Buildbucket for the build status
[00:27:01.508] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:01.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:225:0) after 1m0s
[00:28:01.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:225:0)
[00:28:01.596] Timer tick, asking Buildbucket for the build status
[00:28:01.728] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:01.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:226:0) after 1m0s
[00:29:01.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:226:0)
[00:29:01.757] Timer tick, asking Buildbucket for the build status
[00:29:01.831] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:01.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:227:0) after 1m0s
[00:30:01.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:227:0)
[00:30:01.861] Timer tick, asking Buildbucket for the build status
[00:30:01.931] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:01.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:228:0) after 1m0s
[00:31:02.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:228:0)
[00:31:02.228] Timer tick, asking Buildbucket for the build status
[00:31:02.296] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:02.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:229:0) after 1m0s
[00:32:02.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:229:0)
[00:32:02.319] Timer tick, asking Buildbucket for the build status
[00:32:02.384] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:02.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:230:0) after 1m0s
[00:33:02.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:230:0)
[00:33:02.834] Timer tick, asking Buildbucket for the build status
[00:33:02.911] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:02.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:231:0) after 1m0s
[00:34:02.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:231:0)
[00:34:02.948] Timer tick, asking Buildbucket for the build status
[00:34:03.033] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:03.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:232:0) after 1m0s
[00:35:03.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:232:0)
[00:35:03.385] Timer tick, asking Buildbucket for the build status
[00:35:03.472] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:03.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:233:0) after 1m0s
[00:36:03.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:233:0)
[00:36:03.501] Timer tick, asking Buildbucket for the build status
[00:36:03.570] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:03.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:234:0) after 1m0s
[00:37:03.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:234:0)
[00:37:03.599] Timer tick, asking Buildbucket for the build status
[00:37:03.671] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:03.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:235:0) after 1m0s
[00:38:03.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:235:0)
[00:38:03.789] Timer tick, asking Buildbucket for the build status
[00:38:03.903] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:03.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:236:0) after 1m0s
[00:39:04.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:236:0)
[00:39:04.408] Timer tick, asking Buildbucket for the build status
[00:39:04.523] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:04.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:237:0) after 1m0s
[00:40:04.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:237:0)
[00:40:04.642] Timer tick, asking Buildbucket for the build status
[00:40:04.717] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:04.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:238:0) after 1m0s
[00:41:05.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:238:0)
[00:41:05.188] Timer tick, asking Buildbucket for the build status
[00:41:05.387] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:05.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:239:0) after 1m0s
[00:42:05.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:239:0)
[00:42:05.606] Timer tick, asking Buildbucket for the build status
[00:42:05.714] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:05.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:240:0) after 1m0s
[00:43:06.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:240:0)
[00:43:06.095] Timer tick, asking Buildbucket for the build status
[00:43:06.285] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:06.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:241:0) after 1m0s
[00:44:06.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:241:0)
[00:44:06.652] Timer tick, asking Buildbucket for the build status
[00:44:06.725] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:06.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:242:0) after 1m0s
[00:45:07.233] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:242:0)
[00:45:07.233] Timer tick, asking Buildbucket for the build status
[00:45:07.427] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:07.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:243:0) after 1m0s
[00:46:07.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:243:0)
[00:46:07.500] Timer tick, asking Buildbucket for the build status
[00:46:07.571] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:07.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:244:0) after 1m0s
[00:47:07.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:244:0)
[00:47:07.606] Timer tick, asking Buildbucket for the build status
[00:47:07.673] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:07.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:245:0) after 1m0s
[00:48:07.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:245:0)
[00:48:07.766] Timer tick, asking Buildbucket for the build status
[00:48:07.851] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:07.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:246:0) after 1m0s
[00:49:07.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:246:0)
[00:49:07.983] Timer tick, asking Buildbucket for the build status
[00:49:08.076] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:08.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:247:0) after 1m0s
[00:50:08.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:247:0)
[00:50:08.547] Timer tick, asking Buildbucket for the build status
[00:50:08.718] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:08.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:248:0) after 1m0s
[00:51:08.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:248:0)
[00:51:08.758] Timer tick, asking Buildbucket for the build status
[00:51:08.860] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:08.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:249:0) after 1m0s
[00:52:08.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:249:0)
[00:52:08.885] Timer tick, asking Buildbucket for the build status
[00:52:08.980] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:08.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:250:0) after 1m0s
[00:53:09.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:250:0)
[00:53:09.016] Timer tick, asking Buildbucket for the build status
[00:53:09.196] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:09.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:251:0) after 1m0s
[00:54:09.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:251:0)
[00:54:09.255] Timer tick, asking Buildbucket for the build status
[00:54:09.512] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:09.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:252:0) after 1m0s
[00:55:09.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:252:0)
[00:55:09.539] Timer tick, asking Buildbucket for the build status
[00:55:09.611] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:09.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:253:0) after 1m0s
[00:56:09.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:253:0)
[00:56:09.637] Timer tick, asking Buildbucket for the build status
[00:56:09.758] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:09.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:254:0) after 1m0s
[00:57:10.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:254:0)
[00:57:10.206] Timer tick, asking Buildbucket for the build status
[00:57:10.322] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:10.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:255:0) after 1m0s
[00:58:10.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:255:0)
[00:58:10.383] Timer tick, asking Buildbucket for the build status
[00:58:10.586] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:10.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:256:0) after 1m0s
[00:59:11.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:256:0)
[00:59:11.091] Timer tick, asking Buildbucket for the build status
[00:59:11.150] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:11.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:257:0) after 1m0s
[01:00:11.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:257:0)
[01:00:11.197] Timer tick, asking Buildbucket for the build status
[01:00:11.302] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:11.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:258:0) after 1m0s
[01:01:11.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:258:0)
[01:01:11.373] Timer tick, asking Buildbucket for the build status
[01:01:11.535] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:11.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:259:0) after 1m0s
[01:02:11.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:259:0)
[01:02:11.899] Timer tick, asking Buildbucket for the build status
[01:02:11.941] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:11.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:260:0) after 1m0s
[01:03:12.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:260:0)
[01:03:12.487] Timer tick, asking Buildbucket for the build status
[01:03:12.677] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:12.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:261:0) after 1m0s
[01:04:12.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:261:0)
[01:04:12.871] Timer tick, asking Buildbucket for the build status
[01:04:12.978] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:12.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:262:0) after 1m0s
[01:05:13.010] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:262:0)
[01:05:13.010] Timer tick, asking Buildbucket for the build status
[01:05:13.103] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:13.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:263:0) after 1m0s
[01:06:13.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:263:0)
[01:06:13.133] Timer tick, asking Buildbucket for the build status
[01:06:13.183] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:13.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:264:0) after 1m0s
[01:07:13.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:264:0)
[01:07:13.433] Timer tick, asking Buildbucket for the build status
[01:07:13.585] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:13.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:265:0) after 1m0s
[01:08:13.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:265:0)
[01:08:13.624] Timer tick, asking Buildbucket for the build status
[01:08:14.273] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:14.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:266:0) after 1m0s
[01:09:14.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:266:0)
[01:09:14.303] Timer tick, asking Buildbucket for the build status
[01:09:14.389] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:14.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:267:0) after 1m0s
[01:10:14.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:267:0)
[01:10:14.487] Timer tick, asking Buildbucket for the build status
[01:10:14.578] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:14.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:268:0) after 1m0s
[01:11:15.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:268:0)
[01:11:15.023] Timer tick, asking Buildbucket for the build status
[01:11:15.248] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:15.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:269:0) after 1m0s
[01:12:15.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:269:0)
[01:12:15.325] Timer tick, asking Buildbucket for the build status
[01:12:15.367] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:15.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:270:0) after 1m0s
[01:13:15.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:270:0)
[01:13:15.412] Timer tick, asking Buildbucket for the build status
[01:13:15.578] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:15.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:271:0) after 1m0s
[01:14:15.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:271:0)
[01:14:15.782] Timer tick, asking Buildbucket for the build status
[01:14:15.974] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:15.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:272:0) after 1m0s
[01:15:16.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:272:0)
[01:15:16.496] Timer tick, asking Buildbucket for the build status
[01:15:16.605] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:16.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:273:0) after 1m0s
[01:16:17.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:273:0)
[01:16:17.204] Timer tick, asking Buildbucket for the build status
[01:16:17.242] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:17.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:274:0) after 1m0s
[01:17:17.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:274:0)
[01:17:17.754] Timer tick, asking Buildbucket for the build status
[01:17:17.856] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:17.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:275:0) after 1m0s
[01:18:17.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:275:0)
[01:18:17.889] Timer tick, asking Buildbucket for the build status
[01:18:17.999] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:17.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:276:0) after 1m0s
[01:19:18.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:276:0)
[01:19:18.097] Timer tick, asking Buildbucket for the build status
[01:19:18.307] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:18.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:277:0) after 1m0s
[01:20:18.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:277:0)
[01:20:18.481] Timer tick, asking Buildbucket for the build status
[01:20:18.562] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:18.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:278:0) after 1m0s
[01:21:18.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:278:0)
[01:21:18.640] Timer tick, asking Buildbucket for the build status
[01:21:18.742] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:18.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:279:0) after 1m0s
[01:22:19.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:279:0)
[01:22:19.224] Timer tick, asking Buildbucket for the build status
[01:22:19.279] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:19.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:280:0) after 1m0s
[01:23:19.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:280:0)
[01:23:19.783] Timer tick, asking Buildbucket for the build status
[01:23:19.823] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:19.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:281:0) after 1m0s
[01:24:20.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:281:0)
[01:24:20.291] Timer tick, asking Buildbucket for the build status
[01:24:20.380] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:20.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:282:0) after 1m0s
[01:25:20.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:282:0)
[01:25:20.737] Timer tick, asking Buildbucket for the build status
[01:25:20.788] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:20.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:283:0) after 1m0s
[01:26:21.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:283:0)
[01:26:21.204] Timer tick, asking Buildbucket for the build status
[01:26:21.285] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:21.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:284:0) after 1m0s
[01:27:21.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:284:0)
[01:27:21.701] Timer tick, asking Buildbucket for the build status
[01:27:21.747] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:21.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:285:0) after 1m0s
[01:28:22.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:285:0)
[01:28:22.289] Timer tick, asking Buildbucket for the build status
[01:28:22.387] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:22.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:286:0) after 1m0s
[01:29:22.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:286:0)
[01:29:22.879] Timer tick, asking Buildbucket for the build status
[01:29:22.948] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:22.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:287:0) after 1m0s
[01:30:23.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:287:0)
[01:30:23.455] Timer tick, asking Buildbucket for the build status
[01:30:23.519] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:23.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:288:0) after 1m0s
[01:31:23.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:288:0)
[01:31:23.877] Timer tick, asking Buildbucket for the build status
[01:31:23.945] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:23.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:289:0) after 1m0s
[01:32:24.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:289:0)
[01:32:24.013] Timer tick, asking Buildbucket for the build status
[01:32:24.060] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:24.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:290:0) after 1m0s
[01:33:24.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:290:0)
[01:33:24.599] Timer tick, asking Buildbucket for the build status
[01:33:24.686] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:24.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:291:0) after 1m0s
[01:34:25.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:291:0)
[01:34:25.042] Timer tick, asking Buildbucket for the build status
[01:34:25.141] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:25.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:292:0) after 1m0s
[01:35:25.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:292:0)
[01:35:25.197] Timer tick, asking Buildbucket for the build status
[01:35:25.255] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:25.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:293:0) after 1m0s
[01:36:25.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:293:0)
[01:36:25.285] Timer tick, asking Buildbucket for the build status
[01:36:25.372] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:25.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:294:0) after 1m0s
[01:37:25.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:294:0)
[01:37:25.404] Timer tick, asking Buildbucket for the build status
[01:37:25.472] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:25.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:295:0) after 1m0s
[01:38:25.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:295:0)
[01:38:25.622] Timer tick, asking Buildbucket for the build status
[01:38:25.696] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:25.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:296:0) after 1m0s
[01:39:26.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:296:0)
[01:39:26.052] Timer tick, asking Buildbucket for the build status
[01:39:26.122] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:26.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:297:0) after 1m0s
[01:40:26.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:297:0)
[01:40:26.605] Timer tick, asking Buildbucket for the build status
[01:40:26.652] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:26.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:298:0) after 1m0s
[01:41:26.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:298:0)
[01:41:26.718] Timer tick, asking Buildbucket for the build status
[01:41:26.885] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:26.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:299:0) after 1m0s
[01:42:26.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:299:0)
[01:42:26.915] Timer tick, asking Buildbucket for the build status
[01:42:26.963] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:26.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:300:0) after 1m0s
[01:43:27.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:300:0)
[01:43:27.314] Timer tick, asking Buildbucket for the build status
[01:43:27.364] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:27.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:301:0) after 1m0s
[01:44:27.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:301:0)
[01:44:27.887] Timer tick, asking Buildbucket for the build status
[01:44:27.964] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:27.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:302:0) after 1m0s
[01:45:28.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:302:0)
[01:45:28.486] Timer tick, asking Buildbucket for the build status
[01:45:28.569] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:28.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:303:0) after 1m0s
[01:46:28.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:303:0)
[01:46:28.942] Timer tick, asking Buildbucket for the build status
[01:46:28.998] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:28.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:304:0) after 1m0s
[01:47:29.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:304:0)
[01:47:29.602] Timer tick, asking Buildbucket for the build status
[01:47:30.096] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:30.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:305:0) after 1m0s
[01:48:30.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:305:0)
[01:48:30.129] Timer tick, asking Buildbucket for the build status
[01:48:30.178] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:30.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:306:0) after 1m0s
[01:49:30.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:306:0)
[01:49:30.462] Timer tick, asking Buildbucket for the build status
[01:49:30.534] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:30.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:307:0) after 1m0s
[01:50:30.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:307:0)
[01:50:30.605] Timer tick, asking Buildbucket for the build status
[01:50:30.669] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:30.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:308:0) after 1m0s
[01:51:30.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:308:0)
[01:51:30.696] Timer tick, asking Buildbucket for the build status
[01:51:30.793] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:30.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:309:0) after 1m0s
[01:52:30.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:309:0)
[01:52:30.829] Timer tick, asking Buildbucket for the build status
[01:52:30.888] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:30.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:310:0) after 1m0s
[01:53:30.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:310:0)
[01:53:30.934] Timer tick, asking Buildbucket for the build status
[01:53:30.979] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:30.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:311:0) after 1m0s
[01:54:31.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:311:0)
[01:54:31.374] Timer tick, asking Buildbucket for the build status
[01:54:31.453] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:31.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:312:0) after 1m0s
[01:55:31.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:312:0)
[01:55:31.483] Timer tick, asking Buildbucket for the build status
[01:55:31.628] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:31.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:313:0) after 1m0s
[01:56:32.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:313:0)
[01:56:32.078] Timer tick, asking Buildbucket for the build status
[01:56:32.308] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:32.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:314:0) after 1m0s
[01:57:32.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:314:0)
[01:57:32.774] Timer tick, asking Buildbucket for the build status
[01:57:32.935] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:32.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:315:0) after 1m0s
[01:58:33.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:315:0)
[01:58:33.355] Timer tick, asking Buildbucket for the build status
[01:58:33.399] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:33.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:316:0) after 1m0s
[01:59:33.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:316:0)
[01:59:33.917] Timer tick, asking Buildbucket for the build status
[01:59:34.122] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:34.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:317:0) after 1m0s
[02:00:34.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:317:0)
[02:00:34.577] Timer tick, asking Buildbucket for the build status
[02:00:34.739] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:34.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:318:0) after 1m0s
[02:01:35.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:318:0)
[02:01:35.218] Timer tick, asking Buildbucket for the build status
[02:01:35.413] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:35.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:319:0) after 1m0s
[02:02:35.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:319:0)
[02:02:35.788] Timer tick, asking Buildbucket for the build status
[02:02:35.883] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:35.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:320:0) after 1m0s
[02:03:35.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:320:0)
[02:03:35.926] Timer tick, asking Buildbucket for the build status
[02:03:35.988] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:35.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:321:0) after 1m0s
[02:04:36.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:321:0)
[02:04:36.293] Timer tick, asking Buildbucket for the build status
[02:04:36.469] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:36.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:322:0) after 1m0s
[02:05:36.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:322:0)
[02:05:36.502] Timer tick, asking Buildbucket for the build status
[02:05:36.546] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:36.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:323:0) after 1m0s
[02:06:37.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:323:0)
[02:06:37.021] Timer tick, asking Buildbucket for the build status
[02:06:37.095] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:37.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:324:0) after 1m0s
[02:07:37.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:324:0)
[02:07:37.554] Timer tick, asking Buildbucket for the build status
[02:07:37.658] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:37.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:325:0) after 1m0s
[02:08:37.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:325:0)
[02:08:37.922] Timer tick, asking Buildbucket for the build status
[02:08:38.063] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:38.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:326:0) after 1m0s
[02:09:38.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:326:0)
[02:09:38.191] Timer tick, asking Buildbucket for the build status
[02:09:38.407] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:38.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:327:0) after 1m0s
[02:10:38.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:327:0)
[02:10:38.621] Timer tick, asking Buildbucket for the build status
[02:10:38.796] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:38.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:328:0) after 1m0s
[02:11:39.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:328:0)
[02:11:39.285] Timer tick, asking Buildbucket for the build status
[02:11:39.328] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:39.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:329:0) after 1m0s
[02:12:39.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:329:0)
[02:12:39.865] Timer tick, asking Buildbucket for the build status
[02:12:40.009] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:40.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:330:0) after 1m0s
[02:13:40.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:330:0)
[02:13:40.636] Timer tick, asking Buildbucket for the build status
[02:13:40.710] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:40.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:331:0) after 1m0s
[02:14:40.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:331:0)
[02:14:40.784] Timer tick, asking Buildbucket for the build status
[02:14:40.880] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:40.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:332:0) after 1m0s
[02:15:40.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:332:0)
[02:15:40.926] Timer tick, asking Buildbucket for the build status
[02:15:40.971] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:40.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:333:0) after 1m0s
[02:16:41.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:333:0)
[02:16:41.055] Timer tick, asking Buildbucket for the build status
[02:16:41.194] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:41.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:334:0) after 1m0s
[02:17:41.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:334:0)
[02:17:41.228] Timer tick, asking Buildbucket for the build status
[02:17:41.291] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:41.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:335:0) after 1m0s
[02:18:41.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:335:0)
[02:18:41.320] Timer tick, asking Buildbucket for the build status
[02:18:41.424] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:41.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:336:0) after 1m0s
[02:19:41.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:336:0)
[02:19:41.821] Timer tick, asking Buildbucket for the build status
[02:19:42.029] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:42.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:337:0) after 1m0s
[02:20:42.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:337:0)
[02:20:42.431] Timer tick, asking Buildbucket for the build status
[02:20:42.483] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:42.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:338:0) after 1m0s
[02:21:42.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:338:0)
[02:21:42.522] Timer tick, asking Buildbucket for the build status
[02:21:42.651] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:42.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:339:0) after 1m0s
[02:22:43.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:339:0)
[02:22:43.103] Timer tick, asking Buildbucket for the build status
[02:22:43.230] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:43.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:340:0) after 1m0s
[02:23:43.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:340:0)
[02:23:43.386] Timer tick, asking Buildbucket for the build status
[02:23:43.455] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:43.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:341:0) after 1m0s
[02:24:43.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:341:0)
[02:24:43.525] Timer tick, asking Buildbucket for the build status
[02:24:43.595] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:43.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:342:0) after 1m0s
[02:25:43.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:342:0)
[02:25:43.817] Timer tick, asking Buildbucket for the build status
[02:25:43.867] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:43.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:343:0) after 1m0s
[02:26:43.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:343:0)
[02:26:43.910] Timer tick, asking Buildbucket for the build status
[02:26:43.989] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:43.989] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:344:0) after 1m0s
[02:27:44.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:344:0)
[02:27:44.017] Timer tick, asking Buildbucket for the build status
[02:27:44.074] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:44.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:345:0) after 1m0s
[02:28:44.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:345:0)
[02:28:44.102] Timer tick, asking Buildbucket for the build status
[02:28:44.219] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:44.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:346:0) after 1m0s
[02:29:44.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:346:0)
[02:29:44.250] Timer tick, asking Buildbucket for the build status
[02:29:44.308] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:44.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:347:0) after 1m0s
[02:30:44.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:347:0)
[02:30:44.408] Timer tick, asking Buildbucket for the build status
[02:30:44.487] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:44.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:348:0) after 1m0s
[02:31:44.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:348:0)
[02:31:44.581] Timer tick, asking Buildbucket for the build status
[02:31:44.725] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:44.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:349:0) after 1m0s
[02:32:44.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:349:0)
[02:32:44.760] Timer tick, asking Buildbucket for the build status
[02:32:44.858] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:350:0) after 1m0s
[02:33:44.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:350:0)
[02:33:44.894] Timer tick, asking Buildbucket for the build status
[02:33:44.949] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:44.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:351:0) after 1m0s
[02:34:45.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:351:0)
[02:34:45.071] Timer tick, asking Buildbucket for the build status
[02:34:45.117] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:45.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:352:0) after 1m0s
[02:35:45.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:352:0)
[02:35:45.683] Timer tick, asking Buildbucket for the build status
[02:35:45.765] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:45.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:353:0) after 1m0s
[02:36:46.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:353:0)
[02:36:46.386] Timer tick, asking Buildbucket for the build status
[02:36:46.473] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:46.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:354:0) after 1m0s
[02:37:46.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:354:0)
[02:37:46.992] Timer tick, asking Buildbucket for the build status
[02:37:47.078] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:47.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:355:0) after 1m0s
[02:38:47.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:355:0)
[02:38:47.538] Timer tick, asking Buildbucket for the build status
[02:38:47.627] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:47.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:356:0) after 1m0s
[02:39:48.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:356:0)
[02:39:48.214] Timer tick, asking Buildbucket for the build status
[02:39:48.343] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:48.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:357:0) after 1m0s
[02:40:48.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:357:0)
[02:40:48.418] Timer tick, asking Buildbucket for the build status
[02:40:48.557] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:48.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:358:0) after 1m0s
[02:41:48.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:358:0)
[02:41:48.682] Timer tick, asking Buildbucket for the build status
[02:41:48.841] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:48.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:359:0) after 1m0s
[02:42:48.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:359:0)
[02:42:48.924] Timer tick, asking Buildbucket for the build status
[02:42:48.999] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:48.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:360:0) after 1m0s
[02:43:49.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:360:0)
[02:43:49.034] Timer tick, asking Buildbucket for the build status
[02:43:49.092] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:49.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:361:0) after 1m0s
[02:44:49.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:361:0)
[02:44:49.191] Timer tick, asking Buildbucket for the build status
[02:44:49.252] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:49.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:362:0) after 1m0s
[02:45:49.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:362:0)
[02:45:49.286] Timer tick, asking Buildbucket for the build status
[02:45:49.338] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:49.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:363:0) after 1m0s
[02:46:49.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:363:0)
[02:46:49.385] Timer tick, asking Buildbucket for the build status
[02:46:49.461] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:49.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:364:0) after 1m0s
[02:47:49.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:364:0)
[02:47:49.493] Timer tick, asking Buildbucket for the build status
[02:47:49.569] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:49.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:365:0) after 1m0s
[02:48:49.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:365:0)
[02:48:49.788] Timer tick, asking Buildbucket for the build status
[02:48:49.853] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:49.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:366:0) after 1m0s
[02:49:49.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:366:0)
[02:49:49.911] Timer tick, asking Buildbucket for the build status
[02:49:49.955] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:49.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:367:0) after 1m0s
[02:50:50.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:367:0)
[02:50:50.000] Timer tick, asking Buildbucket for the build status
[02:50:50.283] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:50.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:368:0) after 1m0s
[02:51:50.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:368:0)
[02:51:50.313] Timer tick, asking Buildbucket for the build status
[02:51:50.464] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:50.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:369:0) after 1m0s
[02:52:50.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:369:0)
[02:52:50.507] Timer tick, asking Buildbucket for the build status
[02:52:50.717] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:50.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:370:0) after 1m0s
[02:53:50.888] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:370:0)
[02:53:50.888] Timer tick, asking Buildbucket for the build status
[02:53:50.949] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:50.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:371:0) after 1m0s
[02:54:50.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:371:0)
[02:54:50.980] Timer tick, asking Buildbucket for the build status
[02:54:51.093] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:51.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:372:0) after 1m0s
[02:55:51.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:372:0)
[02:55:51.195] Timer tick, asking Buildbucket for the build status
[02:55:51.249] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:51.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:373:0) after 1m0s
[02:56:51.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:373:0)
[02:56:51.285] Timer tick, asking Buildbucket for the build status
[02:56:51.346] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:51.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:374:0) after 1m0s
[02:57:51.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:374:0)
[02:57:51.376] Timer tick, asking Buildbucket for the build status
[02:57:51.420] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:51.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:375:0) after 1m0s
[02:58:51.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:375:0)
[02:58:51.464] Timer tick, asking Buildbucket for the build status
[02:58:51.554] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:51.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:376:0) after 1m0s
[02:59:51.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:376:0)
[02:59:51.595] Timer tick, asking Buildbucket for the build status
[02:59:51.727] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:51.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:377:0) after 1m0s
[03:00:52.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:377:0)
[03:00:52.258] Timer tick, asking Buildbucket for the build status
[03:00:52.415] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:52.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:378:0) after 1m0s
[03:01:52.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:378:0)
[03:01:52.902] Timer tick, asking Buildbucket for the build status
[03:01:53.132] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:53.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:379:0) after 1m0s
[03:02:53.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:379:0)
[03:02:53.167] Timer tick, asking Buildbucket for the build status
[03:02:53.368] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:53.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:380:0) after 1m0s
[03:03:53.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:380:0)
[03:03:53.410] Timer tick, asking Buildbucket for the build status
[03:03:53.514] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:53.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:381:0) after 1m0s
[03:04:53.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:381:0)
[03:04:53.579] Timer tick, asking Buildbucket for the build status
[03:04:53.657] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:53.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:382:0) after 1m0s
[03:05:53.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:382:0)
[03:05:53.938] Timer tick, asking Buildbucket for the build status
[03:05:54.157] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:54.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:383:0) after 1m0s
[03:06:54.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:383:0)
[03:06:54.201] Timer tick, asking Buildbucket for the build status
[03:06:54.271] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:54.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:384:0) after 1m0s
[03:07:54.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:384:0)
[03:07:54.690] Timer tick, asking Buildbucket for the build status
[03:07:54.752] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:54.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:385:0) after 1m0s
[03:08:55.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:385:0)
[03:08:55.239] Timer tick, asking Buildbucket for the build status
[03:08:55.408] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:55.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:386:0) after 1m0s
[03:09:55.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:386:0)
[03:09:55.439] Timer tick, asking Buildbucket for the build status
[03:09:55.798] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:55.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:387:0) after 1m0s
[03:10:56.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:387:0)
[03:10:56.225] Timer tick, asking Buildbucket for the build status
[03:10:56.322] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:56.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:388:0) after 1m0s
[03:11:56.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:388:0)
[03:11:56.816] Timer tick, asking Buildbucket for the build status
[03:11:57.004] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:57.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:389:0) after 1m0s
[03:12:57.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:389:0)
[03:12:57.487] Timer tick, asking Buildbucket for the build status
[03:12:57.614] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:57.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:390:0) after 1m0s
[03:13:58.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:390:0)
[03:13:58.291] Timer tick, asking Buildbucket for the build status
[03:13:58.340] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:58.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:391:0) after 1m0s
[03:14:58.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:391:0)
[03:14:58.937] Timer tick, asking Buildbucket for the build status
[03:14:59.280] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:59.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:392:0) after 1m0s
[03:15:59.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:392:0)
[03:15:59.325] Timer tick, asking Buildbucket for the build status
[03:15:59.391] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:59.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:393:0) after 1m0s
[03:16:59.421] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:393:0)
[03:16:59.421] Timer tick, asking Buildbucket for the build status
[03:16:59.494] Build 8929215995482730480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:59.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9094646057494210000:394:0) after 1m0s
[03:17:52.796] Received PubSub notification, asking Buildbucket for the build status
[03:17:52.840] Build 8929215995482730480: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[03:17:52.840] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1542856671797624",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542833061672130",
"experimental": true,
"failure_reason": "INFRA_FAILURE",
"id": "8929215995482730480",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"55f40a255928a95a71a5c63b37cdf7cc6ec8acae\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3874-22d9c44\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-east1-b-7805\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1542845845603575",
"status": "COMPLETED",
"status_changed_ts": "1542856672459861",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/9",
"builder:linux-pnacl-x86_64-tests-x86_32",
"buildset:commit/git/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9094646057494210000",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_dimension:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.nacl.toolchain",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/9",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8929215995482730480",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:586d78b1df9b356be85f0824ec8364d9ecbabf90",
"swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
"swarming_tag:buildset:commit/git/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55f40a255928a95a71a5c63b37cdf7cc6ec8acae",
"swarming_tag:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8929215995482730480/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.nacl.toolchain",
"swarming_tag:priority:60",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9094646057494210000",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
"swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:18bb9400d243d9bda9184c768cca1f92d5a22697",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4150d5cb40288810",
"user_agent:luci-scheduler"
],
"updated_ts": "1542856672460809",
"url": "https://ci.chromium.org/b/8929215995482730480",
"utcnow_ts": "1542856672828738"
}
}
[03:17:52.840] Invocation finished in 6h33m32.470357574s with status FAILED