[17:39:46.649] New invocation is queued and will start shortly
[17:39:49.690] Starting the invocation (attempt 1)
[17:39:49.741] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:39:49.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:39:49.741] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9040661877176829888",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-arm\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@f2b7d37238a5f49ba00fc1c152cbd5caa278d030\",\"title\":\"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}}",
"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:9040661877176829888",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"user_agent:luci-scheduler",
"buildset:commit/git/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"gitiles_ref:refs/heads/master"
]
}
[17:39:51.483] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1594316389929669",
"id": "8875231813071633632",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"title\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594316391165853",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4744",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040661877176829888",
"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/8875231813071633632/+/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": "1594316391167785",
"url": "https://ci.chromium.org/b/8875231813071633632",
"utcnow_ts": "1594316391471092"
}
}
[17:39:51.483] Task URL: https://ci.chromium.org/b/8875231813071633632
[17:39:51.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:2:0) after 1m0s
[17:40:51.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:2:0)
[17:40:51.587] Timer tick, asking Buildbucket for the build status
[17:40:51.752] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:51.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:3:0) after 1m0s
[17:41:51.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:3:0)
[17:41:51.945] Timer tick, asking Buildbucket for the build status
[17:41:52.155] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:52.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:4:0) after 1m0s
[17:42:52.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:4:0)
[17:42:52.455] Timer tick, asking Buildbucket for the build status
[17:42:52.676] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:52.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:5:0) after 1m0s
[17:43:52.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:5:0)
[17:43:52.761] Timer tick, asking Buildbucket for the build status
[17:43:52.915] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:52.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:6:0) after 1m0s
[17:44:53.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:6:0)
[17:44:53.832] Timer tick, asking Buildbucket for the build status
[17:44:54.615] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:44:54.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:7:0) after 1m0s
[17:45:55.040] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:7:0)
[17:45:55.040] Timer tick, asking Buildbucket for the build status
[17:45:55.304] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:55.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:8:0) after 1m0s
[17:46:55.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:8:0)
[17:46:55.402] Timer tick, asking Buildbucket for the build status
[17:46:55.596] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:55.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:9:0) after 1m0s
[17:47:55.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:9:0)
[17:47:55.683] Timer tick, asking Buildbucket for the build status
[17:47:55.839] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:55.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:10:0) after 1m0s
[17:48:55.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:10:0)
[17:48:55.993] Timer tick, asking Buildbucket for the build status
[17:48:56.329] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:56.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:11:0) after 1m0s
[17:49:56.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:11:0)
[17:49:56.702] Timer tick, asking Buildbucket for the build status
[17:49:57.297] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:57.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:12:0) after 1m0s
[17:50:57.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:12:0)
[17:50:57.563] Timer tick, asking Buildbucket for the build status
[17:50:57.717] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:57.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:13:0) after 1m0s
[17:51:57.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:13:0)
[17:51:57.771] Timer tick, asking Buildbucket for the build status
[17:51:57.983] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:51:57.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:14:0) after 1m0s
[17:52:58.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:14:0)
[17:52:58.026] Timer tick, asking Buildbucket for the build status
[17:52:58.309] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:52:58.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:15:0) after 1m0s
[17:53:58.359] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:15:0)
[17:53:58.359] Timer tick, asking Buildbucket for the build status
[17:53:58.556] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:58.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:16:0) after 1m0s
[17:54:58.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:16:0)
[17:54:58.605] Timer tick, asking Buildbucket for the build status
[17:54:58.829] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:58.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:17:0) after 1m0s
[17:55:58.883] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:17:0)
[17:55:58.883] Timer tick, asking Buildbucket for the build status
[17:55:59.054] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:59.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:18:0) after 1m0s
[17:56:59.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:18:0)
[17:56:59.048] Timer tick, asking Buildbucket for the build status
[17:56:59.200] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:59.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:19:0) after 1m0s
[17:57:59.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:19:0)
[17:57:59.353] Timer tick, asking Buildbucket for the build status
[17:57:59.509] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:59.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:20:0) after 1m0s
[17:58:59.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:20:0)
[17:58:59.606] Timer tick, asking Buildbucket for the build status
[17:59:00.694] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:00.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:21:0) after 1m0s
[18:00:00.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:21:0)
[18:00:00.795] Timer tick, asking Buildbucket for the build status
[18:00:00.945] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:00.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:22:0) after 1m0s
[18:01:00.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:22:0)
[18:01:00.987] Timer tick, asking Buildbucket for the build status
[18:01:01.162] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:01.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:23:0) after 1m0s
[18:02:02.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:23:0)
[18:02:02.147] Timer tick, asking Buildbucket for the build status
[18:02:02.315] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:02.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:24:0) after 1m0s
[18:03:02.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:24:0)
[18:03:02.790] Timer tick, asking Buildbucket for the build status
[18:03:04.105] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:04.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:25:0) after 1m0s
[18:04:04.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:25:0)
[18:04:04.226] Timer tick, asking Buildbucket for the build status
[18:04:04.390] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:04.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:26:0) after 1m0s
[18:05:04.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:26:0)
[18:05:04.499] Timer tick, asking Buildbucket for the build status
[18:05:04.751] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:04.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:27:0) after 1m0s
[18:06:04.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:27:0)
[18:06:04.752] Timer tick, asking Buildbucket for the build status
[18:06:04.931] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:04.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:28:0) after 1m0s
[18:07:05.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:28:0)
[18:07:05.056] Timer tick, asking Buildbucket for the build status
[18:07:05.204] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:05.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:29:0) after 1m0s
[18:08:05.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:29:0)
[18:08:05.238] Timer tick, asking Buildbucket for the build status
[18:08:05.418] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:05.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:30:0) after 1m0s
[18:09:05.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:30:0)
[18:09:05.475] Timer tick, asking Buildbucket for the build status
[18:09:06.757] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:06.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:31:0) after 1m0s
[18:10:06.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:31:0)
[18:10:06.785] Timer tick, asking Buildbucket for the build status
[18:10:06.929] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:06.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:32:0) after 1m0s
[18:11:06.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:32:0)
[18:11:06.972] Timer tick, asking Buildbucket for the build status
[18:11:07.144] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:07.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:33:0) after 1m0s
[18:12:07.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:33:0)
[18:12:07.182] Timer tick, asking Buildbucket for the build status
[18:12:07.321] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:07.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:34:0) after 1m0s
[18:13:07.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:34:0)
[18:13:07.400] Timer tick, asking Buildbucket for the build status
[18:13:07.537] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:07.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:35:0) after 1m0s
[18:14:07.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:35:0)
[18:14:07.577] Timer tick, asking Buildbucket for the build status
[18:14:11.410] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:11.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:36:0) after 1m0s
[18:15:11.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:36:0)
[18:15:11.448] Timer tick, asking Buildbucket for the build status
[18:15:11.606] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:15:11.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:37:0) after 1m0s
[18:16:11.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:37:0)
[18:16:11.666] Timer tick, asking Buildbucket for the build status
[18:16:12.043] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:16:12.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:38:0) after 1m0s
[18:17:12.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:38:0)
[18:17:12.088] Timer tick, asking Buildbucket for the build status
[18:17:12.351] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:12.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:39:0) after 1m0s
[18:18:12.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:39:0)
[18:18:12.364] Timer tick, asking Buildbucket for the build status
[18:18:12.696] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:12.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:40:0) after 1m0s
[18:19:12.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:40:0)
[18:19:12.770] Timer tick, asking Buildbucket for the build status
[18:19:12.887] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:12.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:41:0) after 1m0s
[18:20:12.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:41:0)
[18:20:12.921] Timer tick, asking Buildbucket for the build status
[18:20:13.103] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:13.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:42:0) after 1m0s
[18:21:13.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:42:0)
[18:21:13.221] Timer tick, asking Buildbucket for the build status
[18:21:13.398] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:13.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:43:0) after 1m0s
[18:22:13.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:43:0)
[18:22:13.474] Timer tick, asking Buildbucket for the build status
[18:22:13.600] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:13.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:44:0) after 1m0s
[18:23:13.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:44:0)
[18:23:13.699] Timer tick, asking Buildbucket for the build status
[18:23:13.792] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:13.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:45:0) after 1m0s
[18:24:13.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:45:0)
[18:24:13.844] Timer tick, asking Buildbucket for the build status
[18:24:14.012] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:14.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:46:0) after 1m0s
[18:25:14.066] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:46:0)
[18:25:14.066] Timer tick, asking Buildbucket for the build status
[18:25:14.194] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:14.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:47:0) after 1m0s
[18:26:14.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:47:0)
[18:26:14.279] Timer tick, asking Buildbucket for the build status
[18:26:15.324] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:15.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:48:0) after 1m0s
[18:27:15.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:48:0)
[18:27:15.355] Timer tick, asking Buildbucket for the build status
[18:27:15.478] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:15.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:49:0) after 1m0s
[18:28:15.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:49:0)
[18:28:15.510] Timer tick, asking Buildbucket for the build status
[18:28:15.804] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:15.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:50:0) after 1m0s
[18:29:15.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:50:0)
[18:29:15.827] Timer tick, asking Buildbucket for the build status
[18:29:17.934] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:17.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:51:0) after 1m0s
[18:30:17.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:51:0)
[18:30:17.942] Timer tick, asking Buildbucket for the build status
[18:30:18.373] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:18.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:52:0) after 1m0s
[18:31:18.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:52:0)
[18:31:18.458] Timer tick, asking Buildbucket for the build status
[18:31:18.603] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:18.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:53:0) after 1m0s
[18:32:18.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:53:0)
[18:32:18.929] Timer tick, asking Buildbucket for the build status
[18:32:19.339] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:19.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:54:0) after 1m0s
[18:33:19.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:54:0)
[18:33:19.524] Timer tick, asking Buildbucket for the build status
[18:33:20.070] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:20.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:55:0) after 1m0s
[18:34:20.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:55:0)
[18:34:20.155] Timer tick, asking Buildbucket for the build status
[18:34:20.655] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:20.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:56:0) after 1m0s
[18:35:20.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:56:0)
[18:35:20.690] Timer tick, asking Buildbucket for the build status
[18:35:21.086] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:21.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:57:0) after 1m0s
[18:36:21.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:57:0)
[18:36:21.118] Timer tick, asking Buildbucket for the build status
[18:36:21.317] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:21.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:58:0) after 1m0s
[18:37:21.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:58:0)
[18:37:21.356] Timer tick, asking Buildbucket for the build status
[18:37:21.694] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:21.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:59:0) after 1m0s
[18:38:21.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:59:0)
[18:38:21.805] Timer tick, asking Buildbucket for the build status
[18:38:22.813] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:22.813] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:60:0) after 1m0s
[18:39:22.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:60:0)
[18:39:22.836] Timer tick, asking Buildbucket for the build status
[18:39:23.015] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:23.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:61:0) after 1m0s
[18:40:23.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:61:0)
[18:40:23.128] Timer tick, asking Buildbucket for the build status
[18:40:23.267] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:23.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:62:0) after 1m0s
[18:41:23.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:62:0)
[18:41:23.332] Timer tick, asking Buildbucket for the build status
[18:41:23.617] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:23.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:63:0) after 1m0s
[18:42:23.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:63:0)
[18:42:23.653] Timer tick, asking Buildbucket for the build status
[18:42:23.851] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:23.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:64:0) after 1m0s
[18:43:23.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:64:0)
[18:43:23.878] Timer tick, asking Buildbucket for the build status
[18:43:24.180] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:24.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:65:0) after 1m0s
[18:44:24.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:65:0)
[18:44:24.207] Timer tick, asking Buildbucket for the build status
[18:44:24.421] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:24.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:66:0) after 1m0s
[18:45:24.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:66:0)
[18:45:24.508] Timer tick, asking Buildbucket for the build status
[18:45:24.952] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:24.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:67:0) after 1m0s
[18:46:24.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:67:0)
[18:46:24.978] Timer tick, asking Buildbucket for the build status
[18:46:25.268] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:25.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:68:0) after 1m0s
[18:47:25.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:68:0)
[18:47:25.294] Timer tick, asking Buildbucket for the build status
[18:47:25.415] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:25.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:69:0) after 1m0s
[18:48:25.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:69:0)
[18:48:25.506] Timer tick, asking Buildbucket for the build status
[18:48:25.591] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:25.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:70:0) after 1m0s
[18:49:25.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:70:0)
[18:49:25.741] Timer tick, asking Buildbucket for the build status
[18:49:25.866] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:25.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:71:0) after 1m0s
[18:50:25.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:71:0)
[18:50:25.967] Timer tick, asking Buildbucket for the build status
[18:50:26.150] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:26.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:72:0) after 1m0s
[18:51:26.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:72:0)
[18:51:26.274] Timer tick, asking Buildbucket for the build status
[18:51:27.559] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:27.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:73:0) after 1m0s
[18:52:27.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:73:0)
[18:52:27.683] Timer tick, asking Buildbucket for the build status
[18:52:27.846] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:27.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:74:0) after 1m0s
[18:53:28.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:74:0)
[18:53:28.038] Timer tick, asking Buildbucket for the build status
[18:53:28.208] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:28.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:75:0) after 1m0s
[18:54:28.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:75:0)
[18:54:28.402] Timer tick, asking Buildbucket for the build status
[18:54:28.566] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:28.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:76:0) after 1m0s
[18:55:28.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:76:0)
[18:55:28.651] Timer tick, asking Buildbucket for the build status
[18:55:28.771] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:28.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:77:0) after 1m0s
[18:56:29.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:77:0)
[18:56:29.015] Timer tick, asking Buildbucket for the build status
[18:56:29.526] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:29.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:78:0) after 1m0s
[18:57:29.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:78:0)
[18:57:29.577] Timer tick, asking Buildbucket for the build status
[18:57:29.730] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:29.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:79:0) after 1m0s
[18:58:29.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:79:0)
[18:58:29.744] Timer tick, asking Buildbucket for the build status
[18:58:29.901] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:29.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:80:0) after 1m0s
[18:59:29.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:80:0)
[18:59:29.926] Timer tick, asking Buildbucket for the build status
[18:59:30.095] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:30.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:81:0) after 1m0s
[19:00:30.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:81:0)
[19:00:30.258] Timer tick, asking Buildbucket for the build status
[19:00:30.454] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:30.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:82:0) after 1m0s
[19:01:30.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:82:0)
[19:01:30.596] Timer tick, asking Buildbucket for the build status
[19:01:30.780] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:30.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:83:0) after 1m0s
[19:02:30.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:83:0)
[19:02:30.827] Timer tick, asking Buildbucket for the build status
[19:02:32.179] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:32.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:84:0) after 1m0s
[19:03:32.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:84:0)
[19:03:32.204] Timer tick, asking Buildbucket for the build status
[19:03:32.480] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:32.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:85:0) after 1m0s
[19:04:32.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:85:0)
[19:04:32.685] Timer tick, asking Buildbucket for the build status
[19:04:32.845] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:32.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:86:0) after 1m0s
[19:05:32.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:86:0)
[19:05:32.918] Timer tick, asking Buildbucket for the build status
[19:05:33.294] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:33.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:87:0) after 1m0s
[19:06:33.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:87:0)
[19:06:33.338] Timer tick, asking Buildbucket for the build status
[19:06:33.453] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:33.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:88:0) after 1m0s
[19:07:33.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:88:0)
[19:07:33.779] Timer tick, asking Buildbucket for the build status
[19:07:33.992] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:33.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:89:0) after 1m0s
[19:08:34.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:89:0)
[19:08:34.259] Timer tick, asking Buildbucket for the build status
[19:08:35.710] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:35.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:90:0) after 1m0s
[19:09:35.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:90:0)
[19:09:35.986] Timer tick, asking Buildbucket for the build status
[19:09:36.180] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:36.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:91:0) after 1m0s
[19:10:36.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:91:0)
[19:10:36.456] Timer tick, asking Buildbucket for the build status
[19:10:36.578] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:36.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:92:0) after 1m0s
[19:11:36.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:92:0)
[19:11:36.665] Timer tick, asking Buildbucket for the build status
[19:11:36.853] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:36.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:93:0) after 1m0s
[19:12:36.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:93:0)
[19:12:36.877] Timer tick, asking Buildbucket for the build status
[19:12:37.012] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:37.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:94:0) after 1m0s
[19:13:37.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:94:0)
[19:13:37.041] Timer tick, asking Buildbucket for the build status
[19:13:37.188] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:37.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:95:0) after 1m0s
[19:14:37.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:95:0)
[19:14:37.224] Timer tick, asking Buildbucket for the build status
[19:14:37.370] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:37.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:96:0) after 1m0s
[19:15:37.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:96:0)
[19:15:37.443] Timer tick, asking Buildbucket for the build status
[19:15:37.822] Build 8875231813071633632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:37.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:97:0) after 1m0s
[19:16:15.720] Received PubSub notification, asking Buildbucket for the build status
[19:16:15.801] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:37.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:97:0)
[19:16:37.853] Timer tick, asking Buildbucket for the build status
[19:16:38.609] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:38.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:99:0) after 1m0s
[19:17:38.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:99:0)
[19:17:38.659] Timer tick, asking Buildbucket for the build status
[19:17:38.923] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:38.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:100:0) after 1m0s
[19:18:38.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:100:0)
[19:18:38.928] Timer tick, asking Buildbucket for the build status
[19:18:39.082] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:39.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:101:0) after 1m0s
[19:19:39.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:101:0)
[19:19:39.106] Timer tick, asking Buildbucket for the build status
[19:19:39.292] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:39.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:102:0) after 1m0s
[19:20:39.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:102:0)
[19:20:39.330] Timer tick, asking Buildbucket for the build status
[19:20:41.773] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:41.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:103:0) after 1m0s
[19:21:41.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:103:0)
[19:21:41.834] Timer tick, asking Buildbucket for the build status
[19:21:41.984] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:104:0) after 1m0s
[19:22:42.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:104:0)
[19:22:42.008] Timer tick, asking Buildbucket for the build status
[19:22:42.170] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:42.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:105:0) after 1m0s
[19:23:42.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:105:0)
[19:23:42.320] Timer tick, asking Buildbucket for the build status
[19:23:42.510] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:42.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:106:0) after 1m0s
[19:24:42.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:106:0)
[19:24:42.528] Timer tick, asking Buildbucket for the build status
[19:24:42.700] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:42.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:107:0) after 1m0s
[19:25:42.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:107:0)
[19:25:42.860] Timer tick, asking Buildbucket for the build status
[19:25:44.806] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:44.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:108:0) after 1m0s
[19:26:44.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:108:0)
[19:26:44.790] Timer tick, asking Buildbucket for the build status
[19:26:44.973] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:44.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:109:0) after 1m0s
[19:27:45.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:109:0)
[19:27:45.156] Timer tick, asking Buildbucket for the build status
[19:27:45.343] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:45.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:110:0) after 1m0s
[19:28:45.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:110:0)
[19:28:45.441] Timer tick, asking Buildbucket for the build status
[19:28:45.785] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:45.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:111:0) after 1m0s
[19:29:45.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:111:0)
[19:29:45.903] Timer tick, asking Buildbucket for the build status
[19:29:46.084] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:46.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:112:0) after 1m0s
[19:30:46.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:112:0)
[19:30:46.185] Timer tick, asking Buildbucket for the build status
[19:30:46.351] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:46.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:113:0) after 1m0s
[19:31:46.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:113:0)
[19:31:46.453] Timer tick, asking Buildbucket for the build status
[19:31:47.231] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:47.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:114:0) after 1m0s
[19:32:47.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:114:0)
[19:32:47.353] Timer tick, asking Buildbucket for the build status
[19:32:47.948] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:47.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:115:0) after 1m0s
[19:33:48.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:115:0)
[19:33:48.216] Timer tick, asking Buildbucket for the build status
[19:33:48.403] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:48.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:116:0) after 1m0s
[19:34:48.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:116:0)
[19:34:48.532] Timer tick, asking Buildbucket for the build status
[19:34:48.976] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:48.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:117:0) after 1m0s
[19:35:49.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:117:0)
[19:35:49.190] Timer tick, asking Buildbucket for the build status
[19:35:49.356] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:49.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:118:0) after 1m0s
[19:36:49.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:118:0)
[19:36:49.465] Timer tick, asking Buildbucket for the build status
[19:36:49.800] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:49.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:119:0) after 1m0s
[19:37:49.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:119:0)
[19:37:49.993] Timer tick, asking Buildbucket for the build status
[19:37:53.869] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:53.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:120:0) after 1m0s
[19:38:54.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:120:0)
[19:38:54.069] Timer tick, asking Buildbucket for the build status
[19:38:54.258] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:54.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:121:0) after 1m0s
[19:39:54.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:121:0)
[19:39:54.460] Timer tick, asking Buildbucket for the build status
[19:39:54.629] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:122:0) after 1m0s
[19:40:54.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:122:0)
[19:40:54.721] Timer tick, asking Buildbucket for the build status
[19:40:54.977] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:54.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:123:0) after 1m0s
[19:41:55.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:123:0)
[19:41:55.087] Timer tick, asking Buildbucket for the build status
[19:41:55.216] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:55.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:124:0) after 1m0s
[19:42:55.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:124:0)
[19:42:55.268] Timer tick, asking Buildbucket for the build status
[19:42:55.454] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:125:0) after 1m0s
[19:43:55.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:125:0)
[19:43:55.463] Timer tick, asking Buildbucket for the build status
[19:43:55.696] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:55.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:126:0) after 1m0s
[19:44:55.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:126:0)
[19:44:55.734] Timer tick, asking Buildbucket for the build status
[19:44:56.029] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:56.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:127:0) after 1m0s
[19:45:56.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:127:0)
[19:45:56.097] Timer tick, asking Buildbucket for the build status
[19:45:56.277] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:56.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:128:0) after 1m0s
[19:46:56.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:128:0)
[19:46:56.312] Timer tick, asking Buildbucket for the build status
[19:46:56.446] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:56.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:129:0) after 1m0s
[19:47:56.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:129:0)
[19:47:56.488] Timer tick, asking Buildbucket for the build status
[19:47:56.772] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:56.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:130:0) after 1m0s
[19:48:56.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:130:0)
[19:48:56.820] Timer tick, asking Buildbucket for the build status
[19:48:57.044] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:57.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:131:0) after 1m0s
[19:49:57.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:131:0)
[19:49:57.095] Timer tick, asking Buildbucket for the build status
[19:49:57.297] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:57.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:132:0) after 1m0s
[19:50:57.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:132:0)
[19:50:57.365] Timer tick, asking Buildbucket for the build status
[19:50:57.629] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:57.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:133:0) after 1m0s
[19:51:57.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:133:0)
[19:51:57.655] Timer tick, asking Buildbucket for the build status
[19:51:57.784] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:57.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:134:0) after 1m0s
[19:52:57.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:134:0)
[19:52:57.855] Timer tick, asking Buildbucket for the build status
[19:52:58.222] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:58.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:135:0) after 1m0s
[19:53:58.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:135:0)
[19:53:58.235] Timer tick, asking Buildbucket for the build status
[19:53:58.430] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:58.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:136:0) after 1m0s
[19:54:58.459] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:136:0)
[19:54:58.459] Timer tick, asking Buildbucket for the build status
[19:54:58.752] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:58.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:137:0) after 1m0s
[19:55:58.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:137:0)
[19:55:58.782] Timer tick, asking Buildbucket for the build status
[19:55:59.102] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:59.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:138:0) after 1m0s
[19:56:59.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:138:0)
[19:56:59.141] Timer tick, asking Buildbucket for the build status
[19:56:59.543] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:59.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:139:0) after 1m0s
[19:57:59.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:139:0)
[19:57:59.574] Timer tick, asking Buildbucket for the build status
[19:58:00.251] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:00.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:140:0) after 1m0s
[19:59:00.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:140:0)
[19:59:00.882] Timer tick, asking Buildbucket for the build status
[19:59:00.992] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:00.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:141:0) after 1m0s
[20:00:01.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:141:0)
[20:00:01.834] Timer tick, asking Buildbucket for the build status
[20:00:01.971] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:01.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:142:0) after 1m0s
[20:01:02.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:142:0)
[20:01:02.016] Timer tick, asking Buildbucket for the build status
[20:01:02.528] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:143:0) after 1m0s
[20:02:02.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:143:0)
[20:02:02.666] Timer tick, asking Buildbucket for the build status
[20:02:03.041] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:03.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:144:0) after 1m0s
[20:03:03.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:144:0)
[20:03:03.104] Timer tick, asking Buildbucket for the build status
[20:03:03.336] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:03.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:145:0) after 1m0s
[20:04:03.399] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:145:0)
[20:04:03.399] Timer tick, asking Buildbucket for the build status
[20:04:04.281] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:04.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:146:0) after 1m0s
[20:05:04.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:146:0)
[20:05:04.375] Timer tick, asking Buildbucket for the build status
[20:05:04.581] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:04.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:147:0) after 1m0s
[20:06:04.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:147:0)
[20:06:04.605] Timer tick, asking Buildbucket for the build status
[20:06:05.855] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:05.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:148:0) after 1m0s
[20:07:05.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:148:0)
[20:07:05.904] Timer tick, asking Buildbucket for the build status
[20:07:07.808] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:07.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:149:0) after 1m0s
[20:08:07.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:149:0)
[20:08:07.804] Timer tick, asking Buildbucket for the build status
[20:08:07.959] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:07.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:150:0) after 1m0s
[20:09:08.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:150:0)
[20:09:08.036] Timer tick, asking Buildbucket for the build status
[20:09:08.371] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:08.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:151:0) after 1m0s
[20:10:08.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:151:0)
[20:10:08.437] Timer tick, asking Buildbucket for the build status
[20:10:08.627] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:08.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:152:0) after 1m0s
[20:11:08.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:152:0)
[20:11:08.644] Timer tick, asking Buildbucket for the build status
[20:11:09.033] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:09.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:153:0) after 1m0s
[20:12:09.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:153:0)
[20:12:09.188] Timer tick, asking Buildbucket for the build status
[20:12:09.434] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:09.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:154:0) after 1m0s
[20:13:09.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:154:0)
[20:13:09.507] Timer tick, asking Buildbucket for the build status
[20:13:10.258] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:10.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:155:0) after 1m0s
[20:14:10.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:155:0)
[20:14:10.313] Timer tick, asking Buildbucket for the build status
[20:14:11.611] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:11.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:156:0) after 1m0s
[20:15:11.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:156:0)
[20:15:11.648] Timer tick, asking Buildbucket for the build status
[20:15:12.157] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:12.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:157:0) after 1m0s
[20:16:12.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:157:0)
[20:16:12.248] Timer tick, asking Buildbucket for the build status
[20:16:12.428] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:12.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:158:0) after 1m0s
[20:17:12.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:158:0)
[20:17:12.427] Timer tick, asking Buildbucket for the build status
[20:17:13.286] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:13.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:159:0) after 1m0s
[20:18:13.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:159:0)
[20:18:13.326] Timer tick, asking Buildbucket for the build status
[20:18:13.771] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:13.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:160:0) after 1m0s
[20:19:13.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:160:0)
[20:19:13.852] Timer tick, asking Buildbucket for the build status
[20:19:14.261] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:14.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:161:0) after 1m0s
[20:20:14.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:161:0)
[20:20:14.302] Timer tick, asking Buildbucket for the build status
[20:20:14.581] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:14.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:162:0) after 1m0s
[20:21:14.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:162:0)
[20:21:14.683] Timer tick, asking Buildbucket for the build status
[20:21:14.826] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:14.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:163:0) after 1m0s
[20:22:14.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:163:0)
[20:22:14.915] Timer tick, asking Buildbucket for the build status
[20:22:15.327] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:15.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:164:0) after 1m0s
[20:23:15.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:164:0)
[20:23:15.386] Timer tick, asking Buildbucket for the build status
[20:23:15.557] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:15.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:165:0) after 1m0s
[20:24:15.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:165:0)
[20:24:15.623] Timer tick, asking Buildbucket for the build status
[20:24:16.233] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:166:0) after 1m0s
[20:25:16.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:166:0)
[20:25:16.282] Timer tick, asking Buildbucket for the build status
[20:25:18.960] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:18.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:167:0) after 1m0s
[20:26:19.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:167:0)
[20:26:19.049] Timer tick, asking Buildbucket for the build status
[20:26:19.446] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:19.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:168:0) after 1m0s
[20:27:19.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:168:0)
[20:27:19.484] Timer tick, asking Buildbucket for the build status
[20:27:19.627] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:19.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:169:0) after 1m0s
[20:28:19.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:169:0)
[20:28:19.708] Timer tick, asking Buildbucket for the build status
[20:28:20.190] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:20.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:170:0) after 1m0s
[20:29:20.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:170:0)
[20:29:20.215] Timer tick, asking Buildbucket for the build status
[20:29:20.382] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:20.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:171:0) after 1m0s
[20:30:20.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:171:0)
[20:30:20.489] Timer tick, asking Buildbucket for the build status
[20:30:21.069] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:21.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:172:0) after 1m0s
[20:31:21.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:172:0)
[20:31:21.148] Timer tick, asking Buildbucket for the build status
[20:31:21.389] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:21.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:173:0) after 1m0s
[20:32:21.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:173:0)
[20:32:21.436] Timer tick, asking Buildbucket for the build status
[20:32:21.655] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:21.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:174:0) after 1m0s
[20:33:21.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:174:0)
[20:33:21.683] Timer tick, asking Buildbucket for the build status
[20:33:21.880] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:21.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:175:0) after 1m0s
[20:34:21.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:175:0)
[20:34:21.955] Timer tick, asking Buildbucket for the build status
[20:34:22.120] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:22.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:176:0) after 1m0s
[20:35:22.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:176:0)
[20:35:22.197] Timer tick, asking Buildbucket for the build status
[20:35:22.428] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:22.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:177:0) after 1m0s
[20:36:22.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:177:0)
[20:36:22.496] Timer tick, asking Buildbucket for the build status
[20:36:22.695] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:22.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:178:0) after 1m0s
[20:37:22.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:178:0)
[20:37:22.768] Timer tick, asking Buildbucket for the build status
[20:37:23.714] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:23.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:179:0) after 1m0s
[20:38:23.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:179:0)
[20:38:23.784] Timer tick, asking Buildbucket for the build status
[20:38:24.171] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:24.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:180:0) after 1m0s
[20:39:24.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:180:0)
[20:39:24.277] Timer tick, asking Buildbucket for the build status
[20:39:24.812] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:24.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:181:0) after 1m0s
[20:40:25.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:181:0)
[20:40:25.042] Timer tick, asking Buildbucket for the build status
[20:40:25.252] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:25.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:182:0) after 1m0s
[20:41:25.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:182:0)
[20:41:25.452] Timer tick, asking Buildbucket for the build status
[20:41:25.640] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:25.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:183:0) after 1m0s
[20:42:25.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:183:0)
[20:42:25.694] Timer tick, asking Buildbucket for the build status
[20:42:25.870] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:25.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:184:0) after 1m0s
[20:43:25.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:184:0)
[20:43:25.943] Timer tick, asking Buildbucket for the build status
[20:43:26.784] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:26.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:185:0) after 1m0s
[20:44:26.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:185:0)
[20:44:26.796] Timer tick, asking Buildbucket for the build status
[20:44:26.950] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:26.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:186:0) after 1m0s
[20:45:27.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:186:0)
[20:45:27.103] Timer tick, asking Buildbucket for the build status
[20:45:27.259] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:27.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:187:0) after 1m0s
[20:46:27.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:187:0)
[20:46:27.334] Timer tick, asking Buildbucket for the build status
[20:46:27.928] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:27.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:188:0) after 1m0s
[20:47:27.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:188:0)
[20:47:27.904] Timer tick, asking Buildbucket for the build status
[20:47:28.100] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:28.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:189:0) after 1m0s
[20:48:28.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:189:0)
[20:48:28.187] Timer tick, asking Buildbucket for the build status
[20:48:28.335] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:28.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:190:0) after 1m0s
[20:49:28.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:190:0)
[20:49:28.320] Timer tick, asking Buildbucket for the build status
[20:49:28.672] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:28.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:191:0) after 1m0s
[20:50:28.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:191:0)
[20:50:28.725] Timer tick, asking Buildbucket for the build status
[20:50:29.188] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:29.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:192:0) after 1m0s
[20:51:29.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:192:0)
[20:51:29.221] Timer tick, asking Buildbucket for the build status
[20:51:29.494] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:29.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:193:0) after 1m0s
[20:52:29.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:193:0)
[20:52:29.587] Timer tick, asking Buildbucket for the build status
[20:52:29.702] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:29.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:194:0) after 1m0s
[20:53:29.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:194:0)
[20:53:29.741] Timer tick, asking Buildbucket for the build status
[20:53:30.214] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:30.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:195:0) after 1m0s
[20:54:30.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:195:0)
[20:54:30.337] Timer tick, asking Buildbucket for the build status
[20:54:30.978] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:30.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:196:0) after 1m0s
[20:55:31.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:196:0)
[20:55:31.145] Timer tick, asking Buildbucket for the build status
[20:55:31.274] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:31.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:197:0) after 1m0s
[20:56:31.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:197:0)
[20:56:31.721] Timer tick, asking Buildbucket for the build status
[20:56:31.925] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:31.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:198:0) after 1m0s
[20:57:32.310] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:198:0)
[20:57:32.310] Timer tick, asking Buildbucket for the build status
[20:57:32.462] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:32.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:199:0) after 1m0s
[20:58:32.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:199:0)
[20:58:32.645] Timer tick, asking Buildbucket for the build status
[20:58:32.853] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:32.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:200:0) after 1m0s
[20:59:32.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:200:0)
[20:59:32.937] Timer tick, asking Buildbucket for the build status
[20:59:33.338] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:33.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:201:0) after 1m0s
[21:00:33.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:201:0)
[21:00:33.685] Timer tick, asking Buildbucket for the build status
[21:00:34.037] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:34.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:202:0) after 1m0s
[21:01:34.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:202:0)
[21:01:34.155] Timer tick, asking Buildbucket for the build status
[21:01:34.646] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:34.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:203:0) after 1m0s
[21:02:34.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:203:0)
[21:02:34.796] Timer tick, asking Buildbucket for the build status
[21:02:35.188] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:35.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:204:0) after 1m0s
[21:03:35.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:204:0)
[21:03:35.249] Timer tick, asking Buildbucket for the build status
[21:03:35.676] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:35.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:205:0) after 1m0s
[21:04:35.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:205:0)
[21:04:35.739] Timer tick, asking Buildbucket for the build status
[21:04:36.322] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:206:0) after 1m0s
[21:05:36.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:206:0)
[21:05:36.458] Timer tick, asking Buildbucket for the build status
[21:05:36.701] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:207:0) after 1m0s
[21:06:36.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:207:0)
[21:06:36.793] Timer tick, asking Buildbucket for the build status
[21:06:37.164] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:37.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:208:0) after 1m0s
[21:07:37.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:208:0)
[21:07:37.222] Timer tick, asking Buildbucket for the build status
[21:07:37.473] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:37.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:209:0) after 1m0s
[21:08:37.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:209:0)
[21:08:37.494] Timer tick, asking Buildbucket for the build status
[21:08:37.711] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:37.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:210:0) after 1m0s
[21:09:37.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:210:0)
[21:09:37.787] Timer tick, asking Buildbucket for the build status
[21:09:37.994] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:37.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:211:0) after 1m0s
[21:10:38.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:211:0)
[21:10:38.026] Timer tick, asking Buildbucket for the build status
[21:10:38.186] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:38.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:212:0) after 1m0s
[21:11:38.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:212:0)
[21:11:38.211] Timer tick, asking Buildbucket for the build status
[21:11:38.459] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:38.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:213:0) after 1m0s
[21:12:38.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:213:0)
[21:12:38.496] Timer tick, asking Buildbucket for the build status
[21:12:38.658] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:38.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:214:0) after 1m0s
[21:13:38.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:214:0)
[21:13:38.695] Timer tick, asking Buildbucket for the build status
[21:13:40.689] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:40.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:215:0) after 1m0s
[21:14:40.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:215:0)
[21:14:40.727] Timer tick, asking Buildbucket for the build status
[21:14:40.988] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:40.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:216:0) after 1m0s
[21:15:41.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:216:0)
[21:15:41.028] Timer tick, asking Buildbucket for the build status
[21:15:41.188] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:41.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:217:0) after 1m0s
[21:16:41.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:217:0)
[21:16:41.218] Timer tick, asking Buildbucket for the build status
[21:16:41.458] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:41.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:218:0) after 1m0s
[21:17:41.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:218:0)
[21:17:41.496] Timer tick, asking Buildbucket for the build status
[21:17:41.637] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:41.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:219:0) after 1m0s
[21:18:41.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:219:0)
[21:18:41.667] Timer tick, asking Buildbucket for the build status
[21:18:42.196] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:42.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:220:0) after 1m0s
[21:19:42.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:220:0)
[21:19:42.253] Timer tick, asking Buildbucket for the build status
[21:19:42.526] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:42.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:221:0) after 1m0s
[21:20:42.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:221:0)
[21:20:42.653] Timer tick, asking Buildbucket for the build status
[21:20:42.889] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:42.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:222:0) after 1m0s
[21:21:42.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:222:0)
[21:21:42.952] Timer tick, asking Buildbucket for the build status
[21:21:43.245] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:43.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:223:0) after 1m0s
[21:22:43.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:223:0)
[21:22:43.324] Timer tick, asking Buildbucket for the build status
[21:22:43.448] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:43.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:224:0) after 1m0s
[21:23:43.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:224:0)
[21:23:43.518] Timer tick, asking Buildbucket for the build status
[21:23:43.937] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:43.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:225:0) after 1m0s
[21:24:44.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:225:0)
[21:24:44.025] Timer tick, asking Buildbucket for the build status
[21:24:44.147] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:44.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:226:0) after 1m0s
[21:25:44.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:226:0)
[21:25:44.323] Timer tick, asking Buildbucket for the build status
[21:25:44.614] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:44.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:227:0) after 1m0s
[21:26:44.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:227:0)
[21:26:44.766] Timer tick, asking Buildbucket for the build status
[21:26:45.358] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:45.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:228:0) after 1m0s
[21:27:45.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:228:0)
[21:27:45.446] Timer tick, asking Buildbucket for the build status
[21:27:45.650] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:45.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:229:0) after 1m0s
[21:28:45.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:229:0)
[21:28:45.785] Timer tick, asking Buildbucket for the build status
[21:28:46.047] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:46.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:230:0) after 1m0s
[21:29:46.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:230:0)
[21:29:46.174] Timer tick, asking Buildbucket for the build status
[21:29:46.386] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:46.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:231:0) after 1m0s
[21:30:46.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:231:0)
[21:30:46.437] Timer tick, asking Buildbucket for the build status
[21:30:46.777] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:46.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:232:0) after 1m0s
[21:31:47.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:232:0)
[21:31:47.117] Timer tick, asking Buildbucket for the build status
[21:31:47.712] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:47.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:233:0) after 1m0s
[21:32:47.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:233:0)
[21:32:47.903] Timer tick, asking Buildbucket for the build status
[21:32:48.041] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:48.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:234:0) after 1m0s
[21:33:48.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:234:0)
[21:33:48.145] Timer tick, asking Buildbucket for the build status
[21:33:48.338] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:48.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:235:0) after 1m0s
[21:34:48.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:235:0)
[21:34:48.431] Timer tick, asking Buildbucket for the build status
[21:34:48.642] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:48.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:236:0) after 1m0s
[21:35:48.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:236:0)
[21:35:48.784] Timer tick, asking Buildbucket for the build status
[21:35:50.849] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:50.849] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:237:0) after 1m0s
[21:36:51.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:237:0)
[21:36:51.057] Timer tick, asking Buildbucket for the build status
[21:36:51.491] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:51.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:238:0) after 1m0s
[21:37:51.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:238:0)
[21:37:51.511] Timer tick, asking Buildbucket for the build status
[21:37:51.642] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:51.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:239:0) after 1m0s
[21:38:51.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:239:0)
[21:38:51.725] Timer tick, asking Buildbucket for the build status
[21:38:51.890] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:51.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:240:0) after 1m0s
[21:39:51.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:240:0)
[21:39:51.950] Timer tick, asking Buildbucket for the build status
[21:39:52.067] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:52.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:241:0) after 1m0s
[21:40:52.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:241:0)
[21:40:52.092] Timer tick, asking Buildbucket for the build status
[21:40:52.273] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:52.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:242:0) after 1m0s
[21:41:52.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:242:0)
[21:41:52.348] Timer tick, asking Buildbucket for the build status
[21:41:56.454] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:56.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:243:0) after 1m0s
[21:42:56.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:243:0)
[21:42:56.501] Timer tick, asking Buildbucket for the build status
[21:42:56.823] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:56.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:244:0) after 1m0s
[21:43:56.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:244:0)
[21:43:56.878] Timer tick, asking Buildbucket for the build status
[21:43:57.073] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:57.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:245:0) after 1m0s
[21:44:57.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:245:0)
[21:44:57.133] Timer tick, asking Buildbucket for the build status
[21:44:57.281] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:57.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:246:0) after 1m0s
[21:45:57.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:246:0)
[21:45:57.321] Timer tick, asking Buildbucket for the build status
[21:45:57.419] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:57.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:247:0) after 1m0s
[21:46:57.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:247:0)
[21:46:57.461] Timer tick, asking Buildbucket for the build status
[21:46:57.876] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:57.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:248:0) after 1m0s
[21:47:57.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:248:0)
[21:47:57.934] Timer tick, asking Buildbucket for the build status
[21:47:58.066] Build 8875231813071633632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:58.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9040661877176829888:249:0) after 1m0s
[21:48:14.323] Received PubSub notification, asking Buildbucket for the build status
[21:48:16.169] Build 8875231813071633632: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:48:16.170] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"completed_ts": "1594331293650545",
"created_by": "project:nacl",
"created_ts": "1594316389929669",
"failure_reason": "BUILD_FAILURE",
"id": "8875231813071633632",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"title\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"title\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-53l0\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-arm\", \"buildnumber\": 4744, \"got_revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f2b7d37238a5f49ba00fc1c152cbd5caa278d030\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-53l0\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5378-e275056\"], \"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": "1594322174337363",
"status": "COMPLETED",
"status_changed_ts": "1594322174770793",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4744",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f2b7d37238a5f49ba00fc1c152cbd5caa278d030",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040661877176829888",
"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/8875231813071633632/+/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:4d4d7b19969d4c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1594331293776559",
"url": "https://ci.chromium.org/b/8875231813071633632",
"utcnow_ts": "1594331296158992"
}
}
[21:48:16.170] Invocation finished in 4h8m29.538773227s with status FAILED