[23:43:58.183] New invocation is queued and will start shortly
[23:43:59.325] Starting the invocation (attempt 1)
[23:43:59.473] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:43:59.473] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:43:59.473] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9078055511306240816",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"0ddc033406886a709b901e0c312872529f9705e8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64-tests-x86_64",
"scheduler_invocation_id:9078055511306240816",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"user_agent:luci-scheduler",
"buildset:commit/git/0ddc033406886a709b901e0c312872529f9705e8",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/0ddc033406886a709b901e0c312872529f9705e8",
"gitiles_ref:refs/heads/master"
]
}
[23:44:05.817] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "project:nacl",
"created_ts": "1558655039841910",
"id": "8912625448901499840",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"0ddc033406886a709b901e0c312872529f9705e8\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558655044250810",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5075",
"builder:linux-pnacl-x86_64-tests-x86_64",
"buildset:commit/git/0ddc033406886a709b901e0c312872529f9705e8",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/0ddc033406886a709b901e0c312872529f9705e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078055511306240816",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8912625448901499840/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558655044250899",
"url": "https://ci.chromium.org/b/8912625448901499840",
"utcnow_ts": "1558655045632361"
}
}
[23:44:05.817] Task URL: https://ci.chromium.org/b/8912625448901499840
[23:44:05.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:2:0) after 1m0s
[23:45:05.521] Received PubSub notification, asking Buildbucket for the build status
[23:45:05.607] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:05.883] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:2:0)
[23:45:05.883] Timer tick, asking Buildbucket for the build status
[23:45:06.192] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:06.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:4:0) after 1m0s
[23:46:06.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:4:0)
[23:46:06.246] Timer tick, asking Buildbucket for the build status
[23:46:06.392] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:06.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:5:0) after 1m0s
[23:47:06.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:5:0)
[23:47:06.425] Timer tick, asking Buildbucket for the build status
[23:47:06.668] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:06.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:6:0) after 1m0s
[23:48:06.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:6:0)
[23:48:06.721] Timer tick, asking Buildbucket for the build status
[23:48:06.982] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:7:0) after 1m0s
[23:49:07.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:7:0)
[23:49:07.001] Timer tick, asking Buildbucket for the build status
[23:49:07.087] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:07.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:8:0) after 1m0s
[23:50:07.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:8:0)
[23:50:07.195] Timer tick, asking Buildbucket for the build status
[23:50:07.298] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:07.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:9:0) after 1m0s
[23:51:07.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:9:0)
[23:51:07.340] Timer tick, asking Buildbucket for the build status
[23:51:07.488] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:07.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:10:0) after 1m0s
[23:52:07.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:10:0)
[23:52:07.522] Timer tick, asking Buildbucket for the build status
[23:52:07.923] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:07.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:11:0) after 1m0s
[23:53:08.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:11:0)
[23:53:08.133] Timer tick, asking Buildbucket for the build status
[23:53:08.543] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:08.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:12:0) after 1m0s
[23:54:08.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:12:0)
[23:54:08.642] Timer tick, asking Buildbucket for the build status
[23:54:08.773] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:08.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:13:0) after 1m0s
[23:55:08.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:13:0)
[23:55:08.747] Timer tick, asking Buildbucket for the build status
[23:55:08.823] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:08.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:14:0) after 1m0s
[23:56:08.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:14:0)
[23:56:08.843] Timer tick, asking Buildbucket for the build status
[23:56:08.941] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:08.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:15:0) after 1m0s
[23:57:09.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:15:0)
[23:57:09.029] Timer tick, asking Buildbucket for the build status
[23:57:09.092] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:09.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:16:0) after 1m0s
[23:58:09.139] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:16:0)
[23:58:09.139] Timer tick, asking Buildbucket for the build status
[23:58:09.250] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:09.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:17:0) after 1m0s
[23:59:09.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:17:0)
[23:59:09.270] Timer tick, asking Buildbucket for the build status
[23:59:09.774] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:09.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:18:0) after 1m0s
[00:00:09.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:18:0)
[00:00:09.860] Timer tick, asking Buildbucket for the build status
[00:00:09.990] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:09.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:19:0) after 1m0s
[00:01:10.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:19:0)
[00:01:10.104] Timer tick, asking Buildbucket for the build status
[00:01:10.434] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:10.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:20:0) after 1m0s
[00:02:10.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:20:0)
[00:02:10.585] Timer tick, asking Buildbucket for the build status
[00:02:10.927] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:10.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:21:0) after 1m0s
[00:03:10.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:21:0)
[00:03:10.932] Timer tick, asking Buildbucket for the build status
[00:03:11.532] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:11.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:22:0) after 1m0s
[00:04:11.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:22:0)
[00:04:11.574] Timer tick, asking Buildbucket for the build status
[00:04:12.435] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:12.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:23:0) after 1m0s
[00:05:12.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:23:0)
[00:05:12.481] Timer tick, asking Buildbucket for the build status
[00:05:12.654] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:12.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:24:0) after 1m0s
[00:06:12.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:24:0)
[00:06:12.719] Timer tick, asking Buildbucket for the build status
[00:06:12.927] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:12.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:25:0) after 1m0s
[00:07:12.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:25:0)
[00:07:12.985] Timer tick, asking Buildbucket for the build status
[00:07:14.946] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:14.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:26:0) after 1m0s
[00:08:14.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:26:0)
[00:08:14.980] Timer tick, asking Buildbucket for the build status
[00:08:15.498] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:15.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:27:0) after 1m0s
[00:09:15.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:27:0)
[00:09:15.543] Timer tick, asking Buildbucket for the build status
[00:09:15.782] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:15.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:28:0) after 1m0s
[00:10:15.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:28:0)
[00:10:15.843] Timer tick, asking Buildbucket for the build status
[00:10:17.038] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:17.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:29:0) after 1m0s
[00:11:17.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:29:0)
[00:11:17.042] Timer tick, asking Buildbucket for the build status
[00:11:18.015] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:18.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:30:0) after 1m0s
[00:12:18.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:30:0)
[00:12:18.163] Timer tick, asking Buildbucket for the build status
[00:12:18.930] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:18.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:31:0) after 1m0s
[00:13:19.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:31:0)
[00:13:19.044] Timer tick, asking Buildbucket for the build status
[00:13:19.670] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:19.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:32:0) after 1m0s
[00:14:19.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:32:0)
[00:14:19.803] Timer tick, asking Buildbucket for the build status
[00:14:20.767] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:20.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:33:0) after 1m0s
[00:15:20.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:33:0)
[00:15:20.851] Timer tick, asking Buildbucket for the build status
[00:15:21.083] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:21.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:34:0) after 1m0s
[00:16:21.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:34:0)
[00:16:21.203] Timer tick, asking Buildbucket for the build status
[00:16:21.676] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:21.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:35:0) after 1m0s
[00:17:21.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:35:0)
[00:17:21.751] Timer tick, asking Buildbucket for the build status
[00:17:21.970] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:21.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:36:0) after 1m0s
[00:18:22.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:36:0)
[00:18:22.007] Timer tick, asking Buildbucket for the build status
[00:18:22.325] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:22.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:37:0) after 1m0s
[00:19:22.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:37:0)
[00:19:22.360] Timer tick, asking Buildbucket for the build status
[00:19:22.899] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:22.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:38:0) after 1m0s
[00:20:22.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:38:0)
[00:20:22.965] Timer tick, asking Buildbucket for the build status
[00:20:23.353] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:23.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:39:0) after 1m0s
[00:21:23.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:39:0)
[00:21:23.342] Timer tick, asking Buildbucket for the build status
[00:21:23.505] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:23.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:40:0) after 1m0s
[00:22:23.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:40:0)
[00:22:23.540] Timer tick, asking Buildbucket for the build status
[00:22:23.995] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:23.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:41:0) after 1m0s
[00:23:24.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:41:0)
[00:23:24.022] Timer tick, asking Buildbucket for the build status
[00:23:24.084] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:24.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:42:0) after 1m0s
[00:24:24.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:42:0)
[00:24:24.107] Timer tick, asking Buildbucket for the build status
[00:24:24.175] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:24.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:43:0) after 1m0s
[00:25:24.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:43:0)
[00:25:24.210] Timer tick, asking Buildbucket for the build status
[00:25:24.489] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:44:0) after 1m0s
[00:26:24.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:44:0)
[00:26:24.519] Timer tick, asking Buildbucket for the build status
[00:26:24.923] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:24.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:45:0) after 1m0s
[00:27:25.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:45:0)
[00:27:25.022] Timer tick, asking Buildbucket for the build status
[00:27:25.179] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:46:0) after 1m0s
[00:28:25.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:46:0)
[00:28:25.200] Timer tick, asking Buildbucket for the build status
[00:28:25.537] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:47:0) after 1m0s
[00:29:25.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:47:0)
[00:29:25.591] Timer tick, asking Buildbucket for the build status
[00:29:25.719] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:25.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:48:0) after 1m0s
[00:30:25.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:48:0)
[00:30:25.719] Timer tick, asking Buildbucket for the build status
[00:30:26.004] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:26.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:49:0) after 1m0s
[00:31:26.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:49:0)
[00:31:26.065] Timer tick, asking Buildbucket for the build status
[00:31:26.148] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:50:0) after 1m0s
[00:32:26.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:50:0)
[00:32:26.293] Timer tick, asking Buildbucket for the build status
[00:32:26.734] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:26.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:51:0) after 1m0s
[00:33:26.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:51:0)
[00:33:26.783] Timer tick, asking Buildbucket for the build status
[00:33:26.866] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:26.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:52:0) after 1m0s
[00:34:26.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:52:0)
[00:34:26.942] Timer tick, asking Buildbucket for the build status
[00:34:27.289] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:27.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:53:0) after 1m0s
[00:35:27.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:53:0)
[00:35:27.357] Timer tick, asking Buildbucket for the build status
[00:35:27.449] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:27.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:54:0) after 1m0s
[00:36:27.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:54:0)
[00:36:27.492] Timer tick, asking Buildbucket for the build status
[00:36:27.634] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:27.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:55:0) after 1m0s
[00:37:27.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:55:0)
[00:37:27.676] Timer tick, asking Buildbucket for the build status
[00:37:28.653] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:28.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:56:0) after 1m0s
[00:38:28.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:56:0)
[00:38:28.783] Timer tick, asking Buildbucket for the build status
[00:38:28.854] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:28.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:57:0) after 1m0s
[00:39:28.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:57:0)
[00:39:28.902] Timer tick, asking Buildbucket for the build status
[00:39:29.069] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:29.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:58:0) after 1m0s
[00:40:29.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:58:0)
[00:40:29.103] Timer tick, asking Buildbucket for the build status
[00:40:29.321] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:29.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:59:0) after 1m0s
[00:41:29.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:59:0)
[00:41:29.371] Timer tick, asking Buildbucket for the build status
[00:41:29.432] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:29.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:60:0) after 1m0s
[00:42:29.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:60:0)
[00:42:29.528] Timer tick, asking Buildbucket for the build status
[00:42:29.600] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:29.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:61:0) after 1m0s
[00:43:29.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:61:0)
[00:43:29.633] Timer tick, asking Buildbucket for the build status
[00:43:30.386] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:30.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:62:0) after 1m0s
[00:44:30.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:62:0)
[00:44:30.392] Timer tick, asking Buildbucket for the build status
[00:44:31.249] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:31.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:63:0) after 1m0s
[00:45:31.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:63:0)
[00:45:31.307] Timer tick, asking Buildbucket for the build status
[00:45:31.655] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:31.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:64:0) after 1m0s
[00:46:31.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:64:0)
[00:46:31.701] Timer tick, asking Buildbucket for the build status
[00:46:31.781] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:31.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:65:0) after 1m0s
[00:47:31.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:65:0)
[00:47:31.895] Timer tick, asking Buildbucket for the build status
[00:47:32.294] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:32.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:66:0) after 1m0s
[00:48:32.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:66:0)
[00:48:32.350] Timer tick, asking Buildbucket for the build status
[00:48:32.469] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:32.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:67:0) after 1m0s
[00:49:32.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:67:0)
[00:49:32.492] Timer tick, asking Buildbucket for the build status
[00:49:33.535] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:33.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:68:0) after 1m0s
[00:50:33.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:68:0)
[00:50:33.584] Timer tick, asking Buildbucket for the build status
[00:50:33.639] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:33.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:69:0) after 1m0s
[00:51:33.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:69:0)
[00:51:33.760] Timer tick, asking Buildbucket for the build status
[00:51:33.942] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:33.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:70:0) after 1m0s
[00:52:34.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:70:0)
[00:52:34.013] Timer tick, asking Buildbucket for the build status
[00:52:34.471] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:34.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:71:0) after 1m0s
[00:53:34.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:71:0)
[00:53:34.503] Timer tick, asking Buildbucket for the build status
[00:53:34.984] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:34.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:72:0) after 1m0s
[00:54:35.040] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:72:0)
[00:54:35.040] Timer tick, asking Buildbucket for the build status
[00:54:35.693] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:35.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:73:0) after 1m0s
[00:55:35.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:73:0)
[00:55:35.690] Timer tick, asking Buildbucket for the build status
[00:55:35.765] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:35.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:74:0) after 1m0s
[00:56:35.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:74:0)
[00:56:35.791] Timer tick, asking Buildbucket for the build status
[00:56:35.988] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:35.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:75:0) after 1m0s
[00:57:42.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:75:0)
[00:57:42.665] Timer tick, asking Buildbucket for the build status
[00:57:42.825] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:42.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:76:0) after 1m0s
[00:58:42.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:76:0)
[00:58:42.865] Timer tick, asking Buildbucket for the build status
[00:58:43.322] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:43.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:77:0) after 1m0s
[00:59:43.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:77:0)
[00:59:43.521] Timer tick, asking Buildbucket for the build status
[00:59:43.599] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:43.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:78:0) after 1m0s
[01:00:43.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:78:0)
[01:00:43.757] Timer tick, asking Buildbucket for the build status
[01:00:43.918] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:43.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:79:0) after 1m0s
[01:01:44.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:79:0)
[01:01:44.054] Timer tick, asking Buildbucket for the build status
[01:01:44.120] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:44.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:80:0) after 1m0s
[01:02:44.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:80:0)
[01:02:44.163] Timer tick, asking Buildbucket for the build status
[01:02:44.945] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:44.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:81:0) after 1m0s
[01:03:45.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:81:0)
[01:03:45.057] Timer tick, asking Buildbucket for the build status
[01:03:45.112] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:45.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:82:0) after 1m0s
[01:04:45.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:82:0)
[01:04:45.195] Timer tick, asking Buildbucket for the build status
[01:04:45.337] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:45.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:83:0) after 1m0s
[01:05:45.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:83:0)
[01:05:45.394] Timer tick, asking Buildbucket for the build status
[01:05:45.959] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:45.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:84:0) after 1m0s
[01:06:45.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:84:0)
[01:06:45.956] Timer tick, asking Buildbucket for the build status
[01:06:46.085] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:46.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:85:0) after 1m0s
[01:07:46.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:85:0)
[01:07:46.182] Timer tick, asking Buildbucket for the build status
[01:07:46.272] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:46.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:86:0) after 1m0s
[01:08:46.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:86:0)
[01:08:46.385] Timer tick, asking Buildbucket for the build status
[01:08:46.434] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:46.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:87:0) after 1m0s
[01:09:46.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:87:0)
[01:09:46.525] Timer tick, asking Buildbucket for the build status
[01:09:46.692] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:46.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:88:0) after 1m0s
[01:10:46.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:88:0)
[01:10:46.748] Timer tick, asking Buildbucket for the build status
[01:10:47.329] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:47.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:89:0) after 1m0s
[01:11:47.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:89:0)
[01:11:47.348] Timer tick, asking Buildbucket for the build status
[01:11:48.535] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:48.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:90:0) after 1m0s
[01:12:48.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:90:0)
[01:12:48.536] Timer tick, asking Buildbucket for the build status
[01:12:48.965] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:48.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:91:0) after 1m0s
[01:13:48.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:91:0)
[01:13:48.987] Timer tick, asking Buildbucket for the build status
[01:13:49.113] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:49.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:92:0) after 1m0s
[01:14:49.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:92:0)
[01:14:49.137] Timer tick, asking Buildbucket for the build status
[01:14:49.275] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:49.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:93:0) after 1m0s
[01:15:49.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:93:0)
[01:15:49.322] Timer tick, asking Buildbucket for the build status
[01:15:49.400] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:49.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:94:0) after 1m0s
[01:16:49.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:94:0)
[01:16:49.429] Timer tick, asking Buildbucket for the build status
[01:16:49.686] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:49.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:95:0) after 1m0s
[01:17:49.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:95:0)
[01:17:49.749] Timer tick, asking Buildbucket for the build status
[01:17:50.951] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:50.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:96:0) after 1m0s
[01:18:50.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:96:0)
[01:18:50.983] Timer tick, asking Buildbucket for the build status
[01:18:51.036] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:51.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:97:0) after 1m0s
[01:19:51.135] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:97:0)
[01:19:51.135] Timer tick, asking Buildbucket for the build status
[01:19:51.274] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:51.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:98:0) after 1m0s
[01:20:51.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:98:0)
[01:20:51.296] Timer tick, asking Buildbucket for the build status
[01:20:51.396] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:51.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:99:0) after 1m0s
[01:21:51.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:99:0)
[01:21:51.463] Timer tick, asking Buildbucket for the build status
[01:21:51.534] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:51.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:100:0) after 1m0s
[01:22:51.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:100:0)
[01:22:51.555] Timer tick, asking Buildbucket for the build status
[01:22:51.604] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:51.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:101:0) after 1m0s
[01:23:51.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:101:0)
[01:23:51.664] Timer tick, asking Buildbucket for the build status
[01:23:51.745] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:51.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:102:0) after 1m0s
[01:24:51.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:102:0)
[01:24:51.836] Timer tick, asking Buildbucket for the build status
[01:24:51.924] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:51.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:103:0) after 1m0s
[01:25:51.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:103:0)
[01:25:51.978] Timer tick, asking Buildbucket for the build status
[01:25:52.117] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:52.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:104:0) after 1m0s
[01:26:52.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:104:0)
[01:26:52.204] Timer tick, asking Buildbucket for the build status
[01:26:52.304] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:52.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:105:0) after 1m0s
[01:27:52.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:105:0)
[01:27:52.375] Timer tick, asking Buildbucket for the build status
[01:27:53.218] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:53.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:106:0) after 1m0s
[01:28:53.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:106:0)
[01:28:53.247] Timer tick, asking Buildbucket for the build status
[01:28:53.812] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:53.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:107:0) after 1m0s
[01:29:53.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:107:0)
[01:29:53.881] Timer tick, asking Buildbucket for the build status
[01:29:54.670] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:54.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:108:0) after 1m0s
[01:30:54.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:108:0)
[01:30:54.729] Timer tick, asking Buildbucket for the build status
[01:30:54.818] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:54.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:109:0) after 1m0s
[01:31:54.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:109:0)
[01:31:54.870] Timer tick, asking Buildbucket for the build status
[01:31:56.003] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:56.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:110:0) after 1m0s
[01:32:56.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:110:0)
[01:32:56.091] Timer tick, asking Buildbucket for the build status
[01:32:56.162] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:56.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:111:0) after 1m0s
[01:33:56.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:111:0)
[01:33:56.244] Timer tick, asking Buildbucket for the build status
[01:33:56.389] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:56.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:112:0) after 1m0s
[01:34:56.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:112:0)
[01:34:56.412] Timer tick, asking Buildbucket for the build status
[01:34:56.558] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:56.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:113:0) after 1m0s
[01:35:56.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:113:0)
[01:35:56.569] Timer tick, asking Buildbucket for the build status
[01:35:57.432] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:57.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:114:0) after 1m0s
[01:36:57.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:114:0)
[01:36:57.525] Timer tick, asking Buildbucket for the build status
[01:36:58.325] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:58.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:115:0) after 1m0s
[01:37:58.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:115:0)
[01:37:58.484] Timer tick, asking Buildbucket for the build status
[01:37:58.533] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:58.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:116:0) after 1m0s
[01:38:58.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:116:0)
[01:38:58.616] Timer tick, asking Buildbucket for the build status
[01:38:58.727] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:58.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:117:0) after 1m0s
[01:39:58.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:117:0)
[01:39:58.807] Timer tick, asking Buildbucket for the build status
[01:39:58.864] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:58.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:118:0) after 1m0s
[01:40:59.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:118:0)
[01:40:59.034] Timer tick, asking Buildbucket for the build status
[01:40:59.152] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:59.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:119:0) after 1m0s
[01:41:59.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:119:0)
[01:41:59.156] Timer tick, asking Buildbucket for the build status
[01:41:59.211] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:59.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:120:0) after 1m0s
[01:42:59.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:120:0)
[01:42:59.272] Timer tick, asking Buildbucket for the build status
[01:42:59.396] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:59.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:121:0) after 1m0s
[01:43:59.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:121:0)
[01:43:59.444] Timer tick, asking Buildbucket for the build status
[01:43:59.498] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:59.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:122:0) after 1m0s
[01:44:59.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:122:0)
[01:44:59.564] Timer tick, asking Buildbucket for the build status
[01:44:59.773] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:59.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:123:0) after 1m0s
[01:45:59.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:123:0)
[01:45:59.797] Timer tick, asking Buildbucket for the build status
[01:46:00.129] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:00.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:124:0) after 1m0s
[01:47:00.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:124:0)
[01:47:00.120] Timer tick, asking Buildbucket for the build status
[01:47:00.198] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:00.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:125:0) after 1m0s
[01:48:00.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:125:0)
[01:48:00.369] Timer tick, asking Buildbucket for the build status
[01:48:00.536] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:00.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:126:0) after 1m0s
[01:49:00.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:126:0)
[01:49:00.589] Timer tick, asking Buildbucket for the build status
[01:49:00.937] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:00.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:127:0) after 1m0s
[01:50:00.953] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:127:0)
[01:50:00.953] Timer tick, asking Buildbucket for the build status
[01:50:01.092] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:01.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:128:0) after 1m0s
[01:51:01.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:128:0)
[01:51:01.125] Timer tick, asking Buildbucket for the build status
[01:51:01.272] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:01.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:129:0) after 1m0s
[01:52:01.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:129:0)
[01:52:01.324] Timer tick, asking Buildbucket for the build status
[01:52:01.447] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:01.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:130:0) after 1m0s
[01:53:01.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:130:0)
[01:53:01.501] Timer tick, asking Buildbucket for the build status
[01:53:01.763] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:01.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:131:0) after 1m0s
[01:54:01.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:131:0)
[01:54:01.850] Timer tick, asking Buildbucket for the build status
[01:54:02.392] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:02.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:132:0) after 1m0s
[01:55:02.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:132:0)
[01:55:02.446] Timer tick, asking Buildbucket for the build status
[01:55:02.588] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:02.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:133:0) after 1m0s
[01:56:02.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:133:0)
[01:56:02.608] Timer tick, asking Buildbucket for the build status
[01:56:02.975] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:02.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:134:0) after 1m0s
[01:57:03.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:134:0)
[01:57:03.019] Timer tick, asking Buildbucket for the build status
[01:57:03.404] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:03.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:135:0) after 1m0s
[01:58:03.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:135:0)
[01:58:03.428] Timer tick, asking Buildbucket for the build status
[01:58:05.095] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:05.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:136:0) after 1m0s
[01:59:05.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:136:0)
[01:59:05.136] Timer tick, asking Buildbucket for the build status
[01:59:05.229] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:05.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:137:0) after 1m0s
[02:00:05.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:137:0)
[02:00:05.234] Timer tick, asking Buildbucket for the build status
[02:00:05.568] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:05.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:138:0) after 1m0s
[02:01:05.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:138:0)
[02:01:05.616] Timer tick, asking Buildbucket for the build status
[02:01:05.718] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:05.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:139:0) after 1m0s
[02:02:05.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:139:0)
[02:02:05.847] Timer tick, asking Buildbucket for the build status
[02:02:06.566] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:06.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:140:0) after 1m0s
[02:03:06.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:140:0)
[02:03:06.631] Timer tick, asking Buildbucket for the build status
[02:03:06.674] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:06.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:141:0) after 1m0s
[02:04:06.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:141:0)
[02:04:06.706] Timer tick, asking Buildbucket for the build status
[02:04:06.939] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:06.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:142:0) after 1m0s
[02:05:07.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:142:0)
[02:05:07.013] Timer tick, asking Buildbucket for the build status
[02:05:07.158] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:07.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:143:0) after 1m0s
[02:06:07.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:143:0)
[02:06:07.185] Timer tick, asking Buildbucket for the build status
[02:06:07.372] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:07.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:144:0) after 1m0s
[02:07:07.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:144:0)
[02:07:07.487] Timer tick, asking Buildbucket for the build status
[02:07:07.724] Build 8912625448901499840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9078055511306240816:145:0) after 1m0s
[02:07:40.236] Received PubSub notification, asking Buildbucket for the build status
[02:07:40.697] Build 8912625448901499840: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:07:40.697] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1558663658879557",
"created_by": "project:nacl",
"created_ts": "1558655039841910",
"failure_reason": "BUILD_FAILURE",
"id": "8912625448901499840",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"0ddc033406886a709b901e0c312872529f9705e8\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-nacl-toolchain-trusty-0-94l3\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5075, \"got_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-trusty-0-94l3\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558655104842403",
"status": "COMPLETED",
"status_changed_ts": "1558655105270589",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5075",
"builder:linux-pnacl-x86_64-tests-x86_64",
"buildset:commit/git/0ddc033406886a709b901e0c312872529f9705e8",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/0ddc033406886a709b901e0c312872529f9705e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078055511306240816",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8912625448901499840/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:44ffe616e67e7c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558663659139144",
"url": "https://ci.chromium.org/b/8912625448901499840",
"utcnow_ts": "1558663660587776"
}
}
[02:07:40.697] Invocation finished in 2h23m42.532270273s with status FAILED