[23:48:09.095] New invocation is queued and will start shortly
[23:48:11.039] Starting the invocation (attempt 1)
[23:48:11.069] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:48:11.069] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:48:11.069] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9076696293709624272",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-arm\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c4cf6f0621b94cca4053b19fa01ef329f5663880\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64-tests-arm",
"scheduler_invocation_id:9076696293709624272",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"user_agent:luci-scheduler",
"buildset:commit/git/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"gitiles_ref:refs/heads/master"
]
}
[23:48:13.290] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1559951291259021",
"id": "8911266230774945888",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559951293018006",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4711",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076696293709624272",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8911266230774945888/+/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": "1559951293018110",
"url": "https://ci.chromium.org/b/8911266230774945888",
"utcnow_ts": "1559951293269414"
}
}
[23:48:13.290] Task URL: https://ci.chromium.org/b/8911266230774945888
[23:48:13.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:2:0) after 1m0s
[23:48:45.300] Received PubSub notification, asking Buildbucket for the build status
[23:48:45.372] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:13.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:2:0)
[23:49:13.322] Timer tick, asking Buildbucket for the build status
[23:49:13.547] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:13.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:4:0) after 1m0s
[23:50:13.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:4:0)
[23:50:13.587] Timer tick, asking Buildbucket for the build status
[23:50:13.665] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:13.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:5:0) after 1m0s
[23:51:13.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:5:0)
[23:51:13.728] Timer tick, asking Buildbucket for the build status
[23:51:13.830] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:13.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:6:0) after 1m0s
[23:52:13.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:6:0)
[23:52:13.968] Timer tick, asking Buildbucket for the build status
[23:52:14.046] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:14.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:7:0) after 1m0s
[23:53:14.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:7:0)
[23:53:14.104] Timer tick, asking Buildbucket for the build status
[23:53:14.272] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:14.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:8:0) after 1m0s
[23:54:14.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:8:0)
[23:54:14.272] Timer tick, asking Buildbucket for the build status
[23:54:14.593] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:14.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:9:0) after 1m0s
[23:55:14.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:9:0)
[23:55:14.658] Timer tick, asking Buildbucket for the build status
[23:55:15.124] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:15.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:10:0) after 1m0s
[23:56:15.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:10:0)
[23:56:15.269] Timer tick, asking Buildbucket for the build status
[23:56:15.390] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:15.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:11:0) after 1m0s
[23:57:15.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:11:0)
[23:57:15.441] Timer tick, asking Buildbucket for the build status
[23:57:15.584] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:15.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:12:0) after 1m0s
[23:58:15.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:12:0)
[23:58:15.640] Timer tick, asking Buildbucket for the build status
[23:58:15.733] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:15.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:13:0) after 1m0s
[23:59:15.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:13:0)
[23:59:15.783] Timer tick, asking Buildbucket for the build status
[23:59:15.891] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:15.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:14:0) after 1m0s
[00:00:16.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:14:0)
[00:00:16.031] Timer tick, asking Buildbucket for the build status
[00:00:16.125] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:16.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:15:0) after 1m0s
[00:01:16.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:15:0)
[00:01:16.160] Timer tick, asking Buildbucket for the build status
[00:01:16.630] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:16.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:16:0) after 1m0s
[00:02:16.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:16:0)
[00:02:16.695] Timer tick, asking Buildbucket for the build status
[00:02:17.022] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:17.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:17:0) after 1m0s
[00:03:17.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:17:0)
[00:03:17.088] Timer tick, asking Buildbucket for the build status
[00:03:17.165] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:17.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:18:0) after 1m0s
[00:04:17.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:18:0)
[00:04:17.215] Timer tick, asking Buildbucket for the build status
[00:04:17.440] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:17.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:19:0) after 1m0s
[00:05:17.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:19:0)
[00:05:17.495] Timer tick, asking Buildbucket for the build status
[00:05:17.546] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:17.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:20:0) after 1m0s
[00:06:17.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:20:0)
[00:06:17.594] Timer tick, asking Buildbucket for the build status
[00:06:18.027] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:18.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:21:0) after 1m0s
[00:07:18.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:21:0)
[00:07:18.125] Timer tick, asking Buildbucket for the build status
[00:07:18.449] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:18.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:22:0) after 1m0s
[00:08:18.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:22:0)
[00:08:18.589] Timer tick, asking Buildbucket for the build status
[00:08:19.483] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:19.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:23:0) after 1m0s
[00:09:19.485] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:23:0)
[00:09:19.485] Timer tick, asking Buildbucket for the build status
[00:09:20.573] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:20.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:24:0) after 1m0s
[00:12:52.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:24:0)
[00:12:52.596] Timer tick, asking Buildbucket for the build status
[00:12:52.695] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:52.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:25:0) after 1m0s
[00:13:52.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:25:0)
[00:13:52.717] Timer tick, asking Buildbucket for the build status
[00:13:53.608] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:53.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:26:0) after 1m0s
[00:14:53.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:26:0)
[00:14:53.640] Timer tick, asking Buildbucket for the build status
[00:14:55.094] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:55.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:27:0) after 1m0s
[00:15:55.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:27:0)
[00:15:55.122] Timer tick, asking Buildbucket for the build status
[00:15:55.194] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:55.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:28:0) after 1m0s
[00:16:55.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:28:0)
[00:16:55.321] Timer tick, asking Buildbucket for the build status
[00:16:56.289] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:56.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:29:0) after 1m0s
[00:17:56.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:29:0)
[00:17:56.344] Timer tick, asking Buildbucket for the build status
[00:17:56.456] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:30:0) after 1m0s
[00:18:56.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:30:0)
[00:18:56.539] Timer tick, asking Buildbucket for the build status
[00:18:57.266] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:57.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:31:0) after 1m0s
[00:19:57.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:31:0)
[00:19:57.287] Timer tick, asking Buildbucket for the build status
[00:19:57.797] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:57.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:32:0) after 1m0s
[00:20:57.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:32:0)
[00:20:57.872] Timer tick, asking Buildbucket for the build status
[00:20:58.146] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:58.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:33:0) after 1m0s
[00:21:58.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:33:0)
[00:21:58.182] Timer tick, asking Buildbucket for the build status
[00:21:58.268] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:58.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:34:0) after 1m0s
[00:22:58.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:34:0)
[00:22:58.287] Timer tick, asking Buildbucket for the build status
[00:22:58.433] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:35:0) after 1m0s
[00:23:58.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:35:0)
[00:23:58.526] Timer tick, asking Buildbucket for the build status
[00:23:58.591] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:58.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:36:0) after 1m0s
[00:24:58.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:36:0)
[00:24:58.602] Timer tick, asking Buildbucket for the build status
[00:24:58.677] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:58.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:37:0) after 1m0s
[00:25:58.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:37:0)
[00:25:58.748] Timer tick, asking Buildbucket for the build status
[00:25:58.835] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:58.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:38:0) after 1m0s
[00:26:58.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:38:0)
[00:26:58.863] Timer tick, asking Buildbucket for the build status
[00:26:58.928] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:58.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:39:0) after 1m0s
[00:27:59.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:39:0)
[00:27:59.026] Timer tick, asking Buildbucket for the build status
[00:27:59.378] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:59.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:40:0) after 1m0s
[00:28:59.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:40:0)
[00:28:59.398] Timer tick, asking Buildbucket for the build status
[00:28:59.467] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:59.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:41:0) after 1m0s
[00:29:59.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:41:0)
[00:29:59.562] Timer tick, asking Buildbucket for the build status
[00:30:00.152] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:00.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:42:0) after 1m0s
[00:31:00.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:42:0)
[00:31:00.245] Timer tick, asking Buildbucket for the build status
[00:31:00.677] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:00.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:43:0) after 1m0s
[00:32:00.662] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:43:0)
[00:32:00.662] Timer tick, asking Buildbucket for the build status
[00:32:01.001] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:01.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:44:0) after 1m0s
[00:33:01.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:44:0)
[00:33:01.008] Timer tick, asking Buildbucket for the build status
[00:33:01.299] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:01.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:45:0) after 1m0s
[00:34:01.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:45:0)
[00:34:01.322] Timer tick, asking Buildbucket for the build status
[00:34:03.806] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:03.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:46:0) after 1m0s
[00:35:03.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:46:0)
[00:35:03.837] Timer tick, asking Buildbucket for the build status
[00:35:03.874] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:03.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:47:0) after 1m0s
[00:36:03.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:47:0)
[00:36:03.914] Timer tick, asking Buildbucket for the build status
[00:36:03.971] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:03.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:48:0) after 1m0s
[00:37:04.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:48:0)
[00:37:04.111] Timer tick, asking Buildbucket for the build status
[00:37:04.148] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:04.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:49:0) after 1m0s
[00:38:04.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:49:0)
[00:38:04.204] Timer tick, asking Buildbucket for the build status
[00:38:04.570] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:04.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:50:0) after 1m0s
[00:39:04.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:50:0)
[00:39:04.590] Timer tick, asking Buildbucket for the build status
[00:39:05.002] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:05.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:51:0) after 1m0s
[00:40:05.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:51:0)
[00:40:05.026] Timer tick, asking Buildbucket for the build status
[00:40:05.142] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:05.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:52:0) after 1m0s
[00:41:05.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:52:0)
[00:41:05.200] Timer tick, asking Buildbucket for the build status
[00:41:05.253] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:05.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:53:0) after 1m0s
[00:42:05.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:53:0)
[00:42:05.331] Timer tick, asking Buildbucket for the build status
[00:42:05.472] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:05.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:54:0) after 1m0s
[00:43:05.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:54:0)
[00:43:05.493] Timer tick, asking Buildbucket for the build status
[00:43:05.687] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:05.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:55:0) after 1m0s
[00:44:05.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:55:0)
[00:44:05.770] Timer tick, asking Buildbucket for the build status
[00:44:05.827] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:05.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:56:0) after 1m0s
[00:45:05.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:56:0)
[00:45:05.857] Timer tick, asking Buildbucket for the build status
[00:45:05.972] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:05.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:57:0) after 1m0s
[00:46:06.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:57:0)
[00:46:06.075] Timer tick, asking Buildbucket for the build status
[00:46:06.361] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:06.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:58:0) after 1m0s
[00:47:06.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:58:0)
[00:47:06.379] Timer tick, asking Buildbucket for the build status
[00:47:06.434] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:06.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:59:0) after 1m0s
[00:48:06.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:59:0)
[00:48:06.464] Timer tick, asking Buildbucket for the build status
[00:48:06.534] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:06.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:60:0) after 1m0s
[00:49:06.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:60:0)
[00:49:06.554] Timer tick, asking Buildbucket for the build status
[00:49:06.755] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:06.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:61:0) after 1m0s
[00:50:06.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:61:0)
[00:50:06.819] Timer tick, asking Buildbucket for the build status
[00:50:06.912] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:06.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:62:0) after 1m0s
[00:51:06.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:62:0)
[00:51:06.901] Timer tick, asking Buildbucket for the build status
[00:51:06.951] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:06.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:63:0) after 1m0s
[00:52:07.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:63:0)
[00:52:07.162] Timer tick, asking Buildbucket for the build status
[00:52:07.348] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:07.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:64:0) after 1m0s
[00:53:07.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:64:0)
[00:53:07.418] Timer tick, asking Buildbucket for the build status
[00:53:08.082] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:08.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:65:0) after 1m0s
[00:54:08.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:65:0)
[00:54:08.150] Timer tick, asking Buildbucket for the build status
[00:54:08.407] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:08.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:66:0) after 1m0s
[00:55:08.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:66:0)
[00:55:08.519] Timer tick, asking Buildbucket for the build status
[00:55:09.425] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:09.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:67:0) after 1m0s
[00:56:09.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:67:0)
[00:56:09.515] Timer tick, asking Buildbucket for the build status
[00:56:09.704] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:09.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:68:0) after 1m0s
[00:57:09.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:68:0)
[00:57:09.760] Timer tick, asking Buildbucket for the build status
[00:57:09.973] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:09.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:69:0) after 1m0s
[00:58:09.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:69:0)
[00:58:09.996] Timer tick, asking Buildbucket for the build status
[00:58:10.108] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:10.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:70:0) after 1m0s
[00:59:10.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:70:0)
[00:59:10.225] Timer tick, asking Buildbucket for the build status
[00:59:10.326] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:10.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:71:0) after 1m0s
[01:00:10.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:71:0)
[01:00:10.348] Timer tick, asking Buildbucket for the build status
[01:00:10.457] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:10.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:72:0) after 1m0s
[01:01:10.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:72:0)
[01:01:10.500] Timer tick, asking Buildbucket for the build status
[01:01:10.559] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:10.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:73:0) after 1m0s
[01:02:10.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:73:0)
[01:02:10.579] Timer tick, asking Buildbucket for the build status
[01:02:10.953] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:10.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:74:0) after 1m0s
[01:03:11.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:74:0)
[01:03:11.036] Timer tick, asking Buildbucket for the build status
[01:03:11.450] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:11.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:75:0) after 1m0s
[01:04:11.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:75:0)
[01:04:11.477] Timer tick, asking Buildbucket for the build status
[01:04:11.543] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:11.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:76:0) after 1m0s
[01:05:11.586] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:76:0)
[01:05:11.586] Timer tick, asking Buildbucket for the build status
[01:05:11.632] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:11.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:77:0) after 1m0s
[01:06:11.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:77:0)
[01:06:11.871] Timer tick, asking Buildbucket for the build status
[01:06:12.015] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:12.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:78:0) after 1m0s
[01:07:12.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:78:0)
[01:07:12.056] Timer tick, asking Buildbucket for the build status
[01:07:12.339] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:12.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:79:0) after 1m0s
[01:08:12.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:79:0)
[01:08:12.391] Timer tick, asking Buildbucket for the build status
[01:08:12.452] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:12.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:80:0) after 1m0s
[01:09:12.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:80:0)
[01:09:12.511] Timer tick, asking Buildbucket for the build status
[01:09:13.953] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:13.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:81:0) after 1m0s
[01:10:14.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:81:0)
[01:10:14.024] Timer tick, asking Buildbucket for the build status
[01:10:14.834] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:14.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:82:0) after 1m0s
[01:11:14.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:82:0)
[01:11:14.829] Timer tick, asking Buildbucket for the build status
[01:11:15.085] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:15.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:83:0) after 1m0s
[01:12:15.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:83:0)
[01:12:15.119] Timer tick, asking Buildbucket for the build status
[01:12:15.276] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:15.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:84:0) after 1m0s
[01:13:15.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:84:0)
[01:13:15.393] Timer tick, asking Buildbucket for the build status
[01:13:15.486] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:15.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:85:0) after 1m0s
[01:14:15.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:85:0)
[01:14:15.508] Timer tick, asking Buildbucket for the build status
[01:14:15.603] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:15.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:86:0) after 1m0s
[01:15:15.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:86:0)
[01:15:15.648] Timer tick, asking Buildbucket for the build status
[01:15:16.133] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:16.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:87:0) after 1m0s
[01:16:16.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:87:0)
[01:16:16.237] Timer tick, asking Buildbucket for the build status
[01:16:16.807] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:16.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:88:0) after 1m0s
[01:17:16.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:88:0)
[01:17:16.833] Timer tick, asking Buildbucket for the build status
[01:17:20.168] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:20.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:89:0) after 1m0s
[01:18:20.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:89:0)
[01:18:20.468] Timer tick, asking Buildbucket for the build status
[01:18:20.732] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:20.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:90:0) after 1m0s
[01:19:20.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:90:0)
[01:19:20.994] Timer tick, asking Buildbucket for the build status
[01:19:21.182] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:21.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:91:0) after 1m0s
[01:20:21.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:91:0)
[01:20:21.272] Timer tick, asking Buildbucket for the build status
[01:20:21.352] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:21.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:92:0) after 1m0s
[01:21:21.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:92:0)
[01:21:21.406] Timer tick, asking Buildbucket for the build status
[01:21:22.373] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:22.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:93:0) after 1m0s
[01:22:22.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:93:0)
[01:22:22.585] Timer tick, asking Buildbucket for the build status
[01:22:22.810] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:22.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:94:0) after 1m0s
[01:23:22.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:94:0)
[01:23:22.998] Timer tick, asking Buildbucket for the build status
[01:23:23.640] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:23.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:95:0) after 1m0s
[01:24:24.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:95:0)
[01:24:24.706] Timer tick, asking Buildbucket for the build status
[01:24:24.818] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:24.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:96:0) after 1m0s
[01:25:24.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:96:0)
[01:25:24.920] Timer tick, asking Buildbucket for the build status
[01:25:25.362] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:25.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:97:0) after 1m0s
[01:26:25.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:97:0)
[01:26:25.562] Timer tick, asking Buildbucket for the build status
[01:26:25.631] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:25.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:98:0) after 1m0s
[01:27:25.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:98:0)
[01:27:25.654] Timer tick, asking Buildbucket for the build status
[01:27:26.222] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:26.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:99:0) after 1m0s
[01:28:26.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:99:0)
[01:28:26.243] Timer tick, asking Buildbucket for the build status
[01:28:26.377] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:26.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:100:0) after 1m0s
[01:29:26.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:100:0)
[01:29:26.412] Timer tick, asking Buildbucket for the build status
[01:29:26.761] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:26.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:101:0) after 1m0s
[01:30:26.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:101:0)
[01:30:26.818] Timer tick, asking Buildbucket for the build status
[01:30:26.883] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:26.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:102:0) after 1m0s
[01:31:26.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:102:0)
[01:31:26.973] Timer tick, asking Buildbucket for the build status
[01:31:27.012] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:27.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:103:0) after 1m0s
[01:32:27.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:103:0)
[01:32:27.036] Timer tick, asking Buildbucket for the build status
[01:32:28.736] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:28.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:104:0) after 1m0s
[01:33:28.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:104:0)
[01:33:28.736] Timer tick, asking Buildbucket for the build status
[01:33:28.780] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:28.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:105:0) after 1m0s
[01:34:28.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:105:0)
[01:34:28.858] Timer tick, asking Buildbucket for the build status
[01:34:28.921] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:28.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:106:0) after 1m0s
[01:35:28.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:106:0)
[01:35:28.981] Timer tick, asking Buildbucket for the build status
[01:35:29.282] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:29.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:107:0) after 1m0s
[01:36:29.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:107:0)
[01:36:29.302] Timer tick, asking Buildbucket for the build status
[01:36:29.400] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:29.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:108:0) after 1m0s
[01:37:29.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:108:0)
[01:37:29.392] Timer tick, asking Buildbucket for the build status
[01:37:29.609] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:29.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:109:0) after 1m0s
[01:38:29.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:109:0)
[01:38:29.645] Timer tick, asking Buildbucket for the build status
[01:38:29.745] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:29.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:110:0) after 1m0s
[01:39:29.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:110:0)
[01:39:29.832] Timer tick, asking Buildbucket for the build status
[01:39:30.048] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:30.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:111:0) after 1m0s
[01:40:30.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:111:0)
[01:40:30.145] Timer tick, asking Buildbucket for the build status
[01:40:30.439] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:30.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:112:0) after 1m0s
[01:41:30.466] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:112:0)
[01:41:30.466] Timer tick, asking Buildbucket for the build status
[01:41:30.909] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:30.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:113:0) after 1m0s
[01:42:30.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:113:0)
[01:42:30.975] Timer tick, asking Buildbucket for the build status
[01:42:31.068] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:31.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:114:0) after 1m0s
[01:43:31.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:114:0)
[01:43:31.148] Timer tick, asking Buildbucket for the build status
[01:43:31.321] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:31.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:115:0) after 1m0s
[01:44:31.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:115:0)
[01:44:31.410] Timer tick, asking Buildbucket for the build status
[01:44:31.614] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:31.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:116:0) after 1m0s
[01:45:31.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:116:0)
[01:45:31.638] Timer tick, asking Buildbucket for the build status
[01:45:33.023] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:117:0) after 1m0s
[01:46:33.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:117:0)
[01:46:33.104] Timer tick, asking Buildbucket for the build status
[01:46:33.397] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:33.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:118:0) after 1m0s
[01:47:33.459] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:118:0)
[01:47:33.459] Timer tick, asking Buildbucket for the build status
[01:47:33.642] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:33.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:119:0) after 1m0s
[01:48:33.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:119:0)
[01:48:33.665] Timer tick, asking Buildbucket for the build status
[01:48:34.245] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:34.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:120:0) after 1m0s
[01:49:34.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:120:0)
[01:49:34.301] Timer tick, asking Buildbucket for the build status
[01:49:34.390] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:34.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:121:0) after 1m0s
[01:50:34.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:121:0)
[01:50:34.414] Timer tick, asking Buildbucket for the build status
[01:50:34.466] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:122:0) after 1m0s
[01:51:34.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:122:0)
[01:51:34.490] Timer tick, asking Buildbucket for the build status
[01:51:34.587] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:123:0) after 1m0s
[01:52:34.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:123:0)
[01:52:34.675] Timer tick, asking Buildbucket for the build status
[01:52:34.881] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:34.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:124:0) after 1m0s
[01:53:34.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:124:0)
[01:53:34.908] Timer tick, asking Buildbucket for the build status
[01:53:35.150] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:35.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:125:0) after 1m0s
[01:54:35.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:125:0)
[01:54:35.221] Timer tick, asking Buildbucket for the build status
[01:54:35.421] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:35.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:126:0) after 1m0s
[01:55:35.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:126:0)
[01:55:35.514] Timer tick, asking Buildbucket for the build status
[01:55:35.828] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:35.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:127:0) after 1m0s
[01:56:35.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:127:0)
[01:56:35.928] Timer tick, asking Buildbucket for the build status
[01:56:36.764] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:36.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:128:0) after 1m0s
[01:57:36.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:128:0)
[01:57:36.811] Timer tick, asking Buildbucket for the build status
[01:57:36.887] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:36.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:129:0) after 1m0s
[01:58:37.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:129:0)
[01:58:37.109] Timer tick, asking Buildbucket for the build status
[01:58:37.436] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:37.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:130:0) after 1m0s
[01:59:37.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:130:0)
[01:59:37.666] Timer tick, asking Buildbucket for the build status
[01:59:37.926] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:37.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:131:0) after 1m0s
[02:00:37.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:131:0)
[02:00:37.957] Timer tick, asking Buildbucket for the build status
[02:00:38.204] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:38.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:132:0) after 1m0s
[02:01:38.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:132:0)
[02:01:38.319] Timer tick, asking Buildbucket for the build status
[02:01:39.324] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:39.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:133:0) after 1m0s
[02:02:39.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:133:0)
[02:02:39.424] Timer tick, asking Buildbucket for the build status
[02:02:39.615] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:39.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:134:0) after 1m0s
[02:03:39.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:134:0)
[02:03:39.671] Timer tick, asking Buildbucket for the build status
[02:03:39.767] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:39.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:135:0) after 1m0s
[02:04:39.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:135:0)
[02:04:39.808] Timer tick, asking Buildbucket for the build status
[02:04:39.932] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:39.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:136:0) after 1m0s
[02:05:39.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:136:0)
[02:05:39.978] Timer tick, asking Buildbucket for the build status
[02:05:40.702] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:40.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:137:0) after 1m0s
[02:06:40.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:137:0)
[02:06:40.790] Timer tick, asking Buildbucket for the build status
[02:06:41.574] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:41.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:138:0) after 1m0s
[02:07:41.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:138:0)
[02:07:41.573] Timer tick, asking Buildbucket for the build status
[02:07:41.734] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:41.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:139:0) after 1m0s
[02:08:42.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:139:0)
[02:08:42.081] Timer tick, asking Buildbucket for the build status
[02:08:42.434] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:42.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:140:0) after 1m0s
[02:09:42.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:140:0)
[02:09:42.510] Timer tick, asking Buildbucket for the build status
[02:09:42.917] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:42.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:141:0) after 1m0s
[02:10:42.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:141:0)
[02:10:42.955] Timer tick, asking Buildbucket for the build status
[02:10:43.093] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:43.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:142:0) after 1m0s
[02:11:43.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:142:0)
[02:11:43.158] Timer tick, asking Buildbucket for the build status
[02:11:43.685] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:43.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:143:0) after 1m0s
[02:12:43.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:143:0)
[02:12:43.971] Timer tick, asking Buildbucket for the build status
[02:12:44.142] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:44.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:144:0) after 1m0s
[02:13:44.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:144:0)
[02:13:44.221] Timer tick, asking Buildbucket for the build status
[02:13:44.499] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:44.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:145:0) after 1m0s
[02:14:44.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:145:0)
[02:14:44.533] Timer tick, asking Buildbucket for the build status
[02:14:44.653] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:44.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:146:0) after 1m0s
[02:15:44.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:146:0)
[02:15:44.871] Timer tick, asking Buildbucket for the build status
[02:15:45.020] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:45.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:147:0) after 1m0s
[02:16:45.126] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:147:0)
[02:16:45.126] Timer tick, asking Buildbucket for the build status
[02:16:45.362] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:45.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:148:0) after 1m0s
[02:17:45.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:148:0)
[02:17:45.437] Timer tick, asking Buildbucket for the build status
[02:17:45.584] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:45.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:149:0) after 1m0s
[02:18:45.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:149:0)
[02:18:45.655] Timer tick, asking Buildbucket for the build status
[02:18:45.810] Build 8911266230774945888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:45.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9076696293709624272:150:0) after 1m0s
[02:18:48.631] Received PubSub notification, asking Buildbucket for the build status
[02:18:48.708] Build 8911266230774945888: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:18:48.708] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"completed_ts": "1559960327674309",
"created_by": "project:nacl",
"created_ts": "1559951291259021",
"failure_reason": "BUILD_FAILURE",
"id": "8911266230774945888",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\"}}",
"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-1-s5ji\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-arm\", \"buildnumber\": 4711, \"got_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-trusty-1-s5ji\"], \"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\": [\"4332-2f8e532\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559951324821291",
"status": "COMPLETED",
"status_changed_ts": "1559951325076638",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4711",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c4cf6f0621b94cca4053b19fa01ef329f5663880",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076696293709624272",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8911266230774945888/+/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:454d29435ce67710",
"user_agent:luci-scheduler"
],
"updated_ts": "1559960327935302",
"url": "https://ci.chromium.org/b/8911266230774945888",
"utcnow_ts": "1559960328695116"
}
}
[02:18:48.709] Invocation finished in 2h30m39.63119357s with status FAILED