[20:31:25.412] New invocation is queued and will start shortly
[20:31:27.105] Starting the invocation (attempt 1)
[20:31:27.141] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:31:27.141] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:31:27.141] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9086946127970201984",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-arm\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
"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:9086946127970201984",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"user_agent:luci-scheduler",
"buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
"gitiles_ref:refs/heads/master"
]
}
[20:31:29.214] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550176287244074",
"id": "8921516065385504288",
"lease_expiration_ts": "1550327487872268",
"lease_key": "1547926534",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550176288886942",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4700",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086946127970201984",
"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/8921516065385504288/+/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:4306868397a00510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550176288887183",
"url": "https://ci.chromium.org/b/8921516065385504288",
"utcnow_ts": "1550176289194028"
}
}
[20:31:29.214] Task URL: https://ci.chromium.org/b/8921516065385504288
[20:31:29.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:2:0) after 1m0s
[20:32:29.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:2:0)
[20:32:29.232] Timer tick, asking Buildbucket for the build status
[20:32:29.298] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:29.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:3:0) after 1m0s
[20:33:29.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:3:0)
[20:33:29.326] Timer tick, asking Buildbucket for the build status
[20:33:29.654] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:29.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:4:0) after 1m0s
[20:34:29.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:4:0)
[20:34:29.671] Timer tick, asking Buildbucket for the build status
[20:34:29.772] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:29.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:5:0) after 1m0s
[20:35:29.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:5:0)
[20:35:29.787] Timer tick, asking Buildbucket for the build status
[20:35:29.835] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:29.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:6:0) after 1m0s
[20:36:29.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:6:0)
[20:36:29.933] Timer tick, asking Buildbucket for the build status
[20:36:30.147] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:30.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:7:0) after 1m0s
[20:37:30.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:7:0)
[20:37:30.164] Timer tick, asking Buildbucket for the build status
[20:37:30.648] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:30.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:8:0) after 1m0s
[20:38:30.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:8:0)
[20:38:30.668] Timer tick, asking Buildbucket for the build status
[20:38:30.782] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:30.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:9:0) after 1m0s
[20:39:30.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:9:0)
[20:39:30.799] Timer tick, asking Buildbucket for the build status
[20:39:30.843] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:30.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:10:0) after 1m0s
[20:40:30.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:10:0)
[20:40:30.860] Timer tick, asking Buildbucket for the build status
[20:40:31.048] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:31.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:11:0) after 1m0s
[20:41:31.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:11:0)
[20:41:31.069] Timer tick, asking Buildbucket for the build status
[20:41:31.194] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:31.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:12:0) after 1m0s
[20:42:31.209] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:12:0)
[20:42:31.209] Timer tick, asking Buildbucket for the build status
[20:42:31.290] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:31.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:13:0) after 1m0s
[20:43:31.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:13:0)
[20:43:31.307] Timer tick, asking Buildbucket for the build status
[20:43:31.415] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:31.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:14:0) after 1m0s
[20:44:31.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:14:0)
[20:44:31.431] Timer tick, asking Buildbucket for the build status
[20:44:31.535] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:31.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:15:0) after 1m0s
[20:45:31.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:15:0)
[20:45:31.548] Timer tick, asking Buildbucket for the build status
[20:45:31.625] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:31.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:16:0) after 1m0s
[20:46:31.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:16:0)
[20:46:31.639] Timer tick, asking Buildbucket for the build status
[20:46:31.948] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:31.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:17:0) after 1m0s
[20:47:31.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:17:0)
[20:47:31.980] Timer tick, asking Buildbucket for the build status
[20:47:32.029] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:32.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:18:0) after 1m0s
[20:48:32.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:18:0)
[20:48:32.044] Timer tick, asking Buildbucket for the build status
[20:48:32.102] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:32.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:19:0) after 1m0s
[20:49:32.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:19:0)
[20:49:32.116] Timer tick, asking Buildbucket for the build status
[20:49:32.195] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:32.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:20:0) after 1m0s
[20:50:32.209] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:20:0)
[20:50:32.209] Timer tick, asking Buildbucket for the build status
[20:50:32.288] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:32.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:21:0) after 1m0s
[20:51:32.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:21:0)
[20:51:32.327] Timer tick, asking Buildbucket for the build status
[20:51:32.380] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:32.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:22:0) after 1m0s
[20:52:32.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:22:0)
[20:52:32.398] Timer tick, asking Buildbucket for the build status
[20:52:32.597] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:32.597] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:23:0) after 1m0s
[20:53:32.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:23:0)
[20:53:32.628] Timer tick, asking Buildbucket for the build status
[20:53:32.693] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:32.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:24:0) after 1m0s
[20:54:32.709] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:24:0)
[20:54:32.709] Timer tick, asking Buildbucket for the build status
[20:54:32.830] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:32.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:25:0) after 1m0s
[20:55:32.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:25:0)
[20:55:32.880] Timer tick, asking Buildbucket for the build status
[20:55:32.923] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:32.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:26:0) after 1m0s
[20:56:32.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:26:0)
[20:56:32.938] Timer tick, asking Buildbucket for the build status
[20:56:32.991] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:32.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:27:0) after 1m0s
[20:57:33.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:27:0)
[20:57:33.008] Timer tick, asking Buildbucket for the build status
[20:57:33.064] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:33.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:28:0) after 1m0s
[20:58:33.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:28:0)
[20:58:33.084] Timer tick, asking Buildbucket for the build status
[20:58:33.154] Build 8921516065385504288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:33.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:29:0) after 1m0s
[20:59:21.194] Received PubSub notification, asking Buildbucket for the build status
[20:59:21.272] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:33.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:29:0)
[20:59:33.175] Timer tick, asking Buildbucket for the build status
[20:59:33.401] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:33.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:31:0) after 1m0s
[21:00:33.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:31:0)
[21:00:33.416] Timer tick, asking Buildbucket for the build status
[21:00:33.571] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:33.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:32:0) after 1m0s
[21:01:33.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:32:0)
[21:01:33.589] Timer tick, asking Buildbucket for the build status
[21:01:33.642] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:33.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:33:0) after 1m0s
[21:02:33.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:33:0)
[21:02:33.682] Timer tick, asking Buildbucket for the build status
[21:02:34.000] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:34.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:34:0) after 1m0s
[21:03:34.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:34:0)
[21:03:34.019] Timer tick, asking Buildbucket for the build status
[21:03:34.107] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:34.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:35:0) after 1m0s
[21:04:34.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:35:0)
[21:04:34.145] Timer tick, asking Buildbucket for the build status
[21:04:34.487] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:34.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:36:0) after 1m0s
[21:05:34.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:36:0)
[21:05:34.555] Timer tick, asking Buildbucket for the build status
[21:05:34.855] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:34.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:37:0) after 1m0s
[21:06:34.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:37:0)
[21:06:34.882] Timer tick, asking Buildbucket for the build status
[21:06:35.297] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:35.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:38:0) after 1m0s
[21:07:35.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:38:0)
[21:07:35.328] Timer tick, asking Buildbucket for the build status
[21:07:35.390] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:35.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:39:0) after 1m0s
[21:08:35.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:39:0)
[21:08:35.409] Timer tick, asking Buildbucket for the build status
[21:08:35.454] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:35.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:40:0) after 1m0s
[21:09:35.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:40:0)
[21:09:35.472] Timer tick, asking Buildbucket for the build status
[21:09:37.119] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:37.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:41:0) after 1m0s
[21:10:37.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:41:0)
[21:10:37.143] Timer tick, asking Buildbucket for the build status
[21:10:37.315] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:37.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:42:0) after 1m0s
[21:11:37.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:42:0)
[21:11:37.351] Timer tick, asking Buildbucket for the build status
[21:11:37.567] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:37.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:43:0) after 1m0s
[21:12:37.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:43:0)
[21:12:37.584] Timer tick, asking Buildbucket for the build status
[21:12:37.985] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:37.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:44:0) after 1m0s
[21:13:38.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:44:0)
[21:13:38.001] Timer tick, asking Buildbucket for the build status
[21:13:38.055] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:38.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:45:0) after 1m0s
[21:14:38.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:45:0)
[21:14:38.072] Timer tick, asking Buildbucket for the build status
[21:14:38.604] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:38.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:46:0) after 1m0s
[21:15:38.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:46:0)
[21:15:38.620] Timer tick, asking Buildbucket for the build status
[21:15:39.473] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:39.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:47:0) after 1m0s
[21:16:39.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:47:0)
[21:16:39.490] Timer tick, asking Buildbucket for the build status
[21:16:39.551] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:39.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:48:0) after 1m0s
[21:17:39.567] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:48:0)
[21:17:39.567] Timer tick, asking Buildbucket for the build status
[21:17:39.620] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:39.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:49:0) after 1m0s
[21:18:39.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:49:0)
[21:18:39.638] Timer tick, asking Buildbucket for the build status
[21:18:40.417] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:40.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:50:0) after 1m0s
[21:19:40.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:50:0)
[21:19:40.436] Timer tick, asking Buildbucket for the build status
[21:19:40.730] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:40.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:51:0) after 1m0s
[21:20:40.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:51:0)
[21:20:40.751] Timer tick, asking Buildbucket for the build status
[21:20:41.013] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:41.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:52:0) after 1m0s
[21:21:41.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:52:0)
[21:21:41.029] Timer tick, asking Buildbucket for the build status
[21:21:41.590] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:41.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:53:0) after 1m0s
[21:22:41.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:53:0)
[21:22:41.608] Timer tick, asking Buildbucket for the build status
[21:22:41.839] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:41.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:54:0) after 1m0s
[21:23:41.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:54:0)
[21:23:41.871] Timer tick, asking Buildbucket for the build status
[21:23:42.660] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:42.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:55:0) after 1m0s
[21:24:42.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:55:0)
[21:24:42.678] Timer tick, asking Buildbucket for the build status
[21:24:43.404] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:43.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:56:0) after 1m0s
[21:25:43.422] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:56:0)
[21:25:43.422] Timer tick, asking Buildbucket for the build status
[21:25:44.147] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:44.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:57:0) after 1m0s
[21:26:44.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:57:0)
[21:26:44.175] Timer tick, asking Buildbucket for the build status
[21:26:44.891] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:44.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:58:0) after 1m0s
[21:27:44.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:58:0)
[21:27:44.907] Timer tick, asking Buildbucket for the build status
[21:27:45.356] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:45.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:59:0) after 1m0s
[21:28:45.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:59:0)
[21:28:45.383] Timer tick, asking Buildbucket for the build status
[21:28:46.172] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:46.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:60:0) after 1m0s
[21:29:46.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:60:0)
[21:29:46.218] Timer tick, asking Buildbucket for the build status
[21:29:47.194] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:47.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:61:0) after 1m0s
[21:30:47.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:61:0)
[21:30:47.214] Timer tick, asking Buildbucket for the build status
[21:30:47.731] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:47.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:62:0) after 1m0s
[21:31:47.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:62:0)
[21:31:47.748] Timer tick, asking Buildbucket for the build status
[21:31:48.274] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:48.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:63:0) after 1m0s
[21:32:48.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:63:0)
[21:32:48.307] Timer tick, asking Buildbucket for the build status
[21:32:48.558] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:48.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:64:0) after 1m0s
[21:33:48.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:64:0)
[21:33:48.579] Timer tick, asking Buildbucket for the build status
[21:33:49.201] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:49.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:65:0) after 1m0s
[21:34:49.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:65:0)
[21:34:49.222] Timer tick, asking Buildbucket for the build status
[21:34:49.364] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:49.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:66:0) after 1m0s
[21:35:49.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:66:0)
[21:35:49.384] Timer tick, asking Buildbucket for the build status
[21:35:49.715] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:49.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:67:0) after 1m0s
[21:36:49.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:67:0)
[21:36:49.742] Timer tick, asking Buildbucket for the build status
[21:36:50.221] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:50.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:68:0) after 1m0s
[21:37:50.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:68:0)
[21:37:50.242] Timer tick, asking Buildbucket for the build status
[21:37:50.696] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:50.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:69:0) after 1m0s
[21:38:50.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:69:0)
[21:38:50.744] Timer tick, asking Buildbucket for the build status
[21:38:50.911] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:50.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:70:0) after 1m0s
[21:39:50.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:70:0)
[21:39:50.956] Timer tick, asking Buildbucket for the build status
[21:39:51.176] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:51.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:71:0) after 1m0s
[21:40:51.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:71:0)
[21:40:51.193] Timer tick, asking Buildbucket for the build status
[21:40:51.386] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:51.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:72:0) after 1m0s
[21:41:51.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:72:0)
[21:41:51.404] Timer tick, asking Buildbucket for the build status
[21:41:51.555] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:51.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:73:0) after 1m0s
[21:42:51.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:73:0)
[21:42:51.575] Timer tick, asking Buildbucket for the build status
[21:42:52.870] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:52.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:74:0) after 1m0s
[21:43:52.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:74:0)
[21:43:52.887] Timer tick, asking Buildbucket for the build status
[21:43:53.328] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:53.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:75:0) after 1m0s
[21:44:53.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:75:0)
[21:44:53.346] Timer tick, asking Buildbucket for the build status
[21:44:53.939] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:53.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:76:0) after 1m0s
[21:45:53.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:76:0)
[21:45:53.956] Timer tick, asking Buildbucket for the build status
[21:45:54.121] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:54.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:77:0) after 1m0s
[21:46:54.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:77:0)
[21:46:54.141] Timer tick, asking Buildbucket for the build status
[21:46:54.272] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:54.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:78:0) after 1m0s
[21:47:54.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:78:0)
[21:47:54.293] Timer tick, asking Buildbucket for the build status
[21:47:54.349] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:54.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:79:0) after 1m0s
[21:48:54.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:79:0)
[21:48:54.369] Timer tick, asking Buildbucket for the build status
[21:48:54.873] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:80:0) after 1m0s
[21:49:54.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:80:0)
[21:49:54.893] Timer tick, asking Buildbucket for the build status
[21:49:54.944] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:81:0) after 1m0s
[21:50:54.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:81:0)
[21:50:54.973] Timer tick, asking Buildbucket for the build status
[21:50:56.002] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:56.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:82:0) after 1m0s
[21:51:56.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:82:0)
[21:51:56.022] Timer tick, asking Buildbucket for the build status
[21:51:56.626] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:56.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:83:0) after 1m0s
[21:52:56.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:83:0)
[21:52:56.646] Timer tick, asking Buildbucket for the build status
[21:52:56.996] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:56.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:84:0) after 1m0s
[21:53:57.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:84:0)
[21:53:57.014] Timer tick, asking Buildbucket for the build status
[21:53:57.369] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:57.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:85:0) after 1m0s
[21:54:57.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:85:0)
[21:54:57.389] Timer tick, asking Buildbucket for the build status
[21:54:57.464] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:57.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:86:0) after 1m0s
[21:55:57.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:86:0)
[21:55:57.498] Timer tick, asking Buildbucket for the build status
[21:55:57.564] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:57.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:87:0) after 1m0s
[21:56:57.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:87:0)
[21:56:57.581] Timer tick, asking Buildbucket for the build status
[21:56:57.733] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:57.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:88:0) after 1m0s
[21:57:57.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:88:0)
[21:57:57.753] Timer tick, asking Buildbucket for the build status
[21:57:57.846] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:57.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:89:0) after 1m0s
[21:58:57.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:89:0)
[21:58:57.866] Timer tick, asking Buildbucket for the build status
[21:58:58.104] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:58.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:90:0) after 1m0s
[21:59:58.122] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:90:0)
[21:59:58.122] Timer tick, asking Buildbucket for the build status
[21:59:58.229] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:58.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:91:0) after 1m0s
[22:00:58.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:91:0)
[22:00:58.250] Timer tick, asking Buildbucket for the build status
[22:00:58.348] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:58.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:92:0) after 1m0s
[22:01:58.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:92:0)
[22:01:58.388] Timer tick, asking Buildbucket for the build status
[22:01:58.440] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:58.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:93:0) after 1m0s
[22:02:58.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:93:0)
[22:02:58.457] Timer tick, asking Buildbucket for the build status
[22:02:58.854] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:58.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:94:0) after 1m0s
[22:03:58.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:94:0)
[22:03:58.875] Timer tick, asking Buildbucket for the build status
[22:03:58.925] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:58.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:95:0) after 1m0s
[22:04:58.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:95:0)
[22:04:58.946] Timer tick, asking Buildbucket for the build status
[22:05:00.174] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:00.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:96:0) after 1m0s
[22:06:00.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:96:0)
[22:06:00.206] Timer tick, asking Buildbucket for the build status
[22:06:00.254] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:00.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:97:0) after 1m0s
[22:07:00.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:97:0)
[22:07:00.274] Timer tick, asking Buildbucket for the build status
[22:07:00.453] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:00.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:98:0) after 1m0s
[22:08:00.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:98:0)
[22:08:00.491] Timer tick, asking Buildbucket for the build status
[22:08:00.617] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:00.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:99:0) after 1m0s
[22:09:00.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:99:0)
[22:09:00.636] Timer tick, asking Buildbucket for the build status
[22:09:00.716] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:00.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:100:0) after 1m0s
[22:10:00.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:100:0)
[22:10:00.732] Timer tick, asking Buildbucket for the build status
[22:10:00.890] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:00.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:101:0) after 1m0s
[22:11:00.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:101:0)
[22:11:00.908] Timer tick, asking Buildbucket for the build status
[22:11:01.244] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:01.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:102:0) after 1m0s
[22:12:01.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:102:0)
[22:12:01.264] Timer tick, asking Buildbucket for the build status
[22:12:01.318] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:01.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:103:0) after 1m0s
[22:13:01.359] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:103:0)
[22:13:01.359] Timer tick, asking Buildbucket for the build status
[22:13:02.041] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:02.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:104:0) after 1m0s
[22:14:02.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:104:0)
[22:14:02.068] Timer tick, asking Buildbucket for the build status
[22:14:05.011] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:05.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:105:0) after 1m0s
[22:15:05.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:105:0)
[22:15:05.030] Timer tick, asking Buildbucket for the build status
[22:15:05.224] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:05.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:106:0) after 1m0s
[22:16:05.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:106:0)
[22:16:05.242] Timer tick, asking Buildbucket for the build status
[22:16:05.360] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:05.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:107:0) after 1m0s
[22:17:05.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:107:0)
[22:17:05.381] Timer tick, asking Buildbucket for the build status
[22:17:06.167] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:06.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:108:0) after 1m0s
[22:18:06.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:108:0)
[22:18:06.187] Timer tick, asking Buildbucket for the build status
[22:18:06.594] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:06.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:109:0) after 1m0s
[22:19:06.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:109:0)
[22:19:06.613] Timer tick, asking Buildbucket for the build status
[22:19:07.701] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:07.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:110:0) after 1m0s
[22:20:07.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:110:0)
[22:20:07.717] Timer tick, asking Buildbucket for the build status
[22:20:07.899] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:07.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:111:0) after 1m0s
[22:21:07.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:111:0)
[22:21:07.948] Timer tick, asking Buildbucket for the build status
[22:21:08.002] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:08.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:112:0) after 1m0s
[22:22:08.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:112:0)
[22:22:08.224] Timer tick, asking Buildbucket for the build status
[22:22:08.299] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:08.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:113:0) after 1m0s
[22:23:08.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:113:0)
[22:23:08.404] Timer tick, asking Buildbucket for the build status
[22:23:08.512] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:08.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:114:0) after 1m0s
[22:24:08.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:114:0)
[22:24:08.569] Timer tick, asking Buildbucket for the build status
[22:24:08.622] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:08.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:115:0) after 1m0s
[22:25:08.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:115:0)
[22:25:08.797] Timer tick, asking Buildbucket for the build status
[22:25:09.141] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:116:0) after 1m0s
[22:26:09.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:116:0)
[22:26:09.325] Timer tick, asking Buildbucket for the build status
[22:26:09.474] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:09.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:117:0) after 1m0s
[22:27:09.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:117:0)
[22:27:09.645] Timer tick, asking Buildbucket for the build status
[22:27:09.694] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:09.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:118:0) after 1m0s
[22:28:09.724] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:118:0)
[22:28:09.724] Timer tick, asking Buildbucket for the build status
[22:28:09.780] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:09.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:119:0) after 1m0s
[22:29:09.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:119:0)
[22:29:09.804] Timer tick, asking Buildbucket for the build status
[22:29:09.846] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:09.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:120:0) after 1m0s
[22:30:09.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:120:0)
[22:30:09.867] Timer tick, asking Buildbucket for the build status
[22:30:16.096] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:16.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:121:0) after 1m0s
[22:31:16.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:121:0)
[22:31:16.116] Timer tick, asking Buildbucket for the build status
[22:31:16.171] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:16.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:122:0) after 1m0s
[22:32:16.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:122:0)
[22:32:16.188] Timer tick, asking Buildbucket for the build status
[22:32:16.393] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:16.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:123:0) after 1m0s
[22:33:16.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:123:0)
[22:33:16.411] Timer tick, asking Buildbucket for the build status
[22:33:16.472] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:16.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:124:0) after 1m0s
[22:34:16.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:124:0)
[22:34:16.488] Timer tick, asking Buildbucket for the build status
[22:34:16.596] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:16.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:125:0) after 1m0s
[22:35:16.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:125:0)
[22:35:16.635] Timer tick, asking Buildbucket for the build status
[22:35:16.694] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:16.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:126:0) after 1m0s
[22:36:16.715] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:126:0)
[22:36:16.715] Timer tick, asking Buildbucket for the build status
[22:36:16.754] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:16.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:127:0) after 1m0s
[22:37:16.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:127:0)
[22:37:16.777] Timer tick, asking Buildbucket for the build status
[22:37:16.889] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:16.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:128:0) after 1m0s
[22:38:16.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:128:0)
[22:38:16.969] Timer tick, asking Buildbucket for the build status
[22:38:17.077] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:17.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:129:0) after 1m0s
[22:39:17.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:129:0)
[22:39:17.095] Timer tick, asking Buildbucket for the build status
[22:39:17.194] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:17.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:130:0) after 1m0s
[22:40:17.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:130:0)
[22:40:17.213] Timer tick, asking Buildbucket for the build status
[22:40:17.266] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:17.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:131:0) after 1m0s
[22:41:17.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:131:0)
[22:41:17.284] Timer tick, asking Buildbucket for the build status
[22:41:17.332] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:17.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:132:0) after 1m0s
[22:42:17.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:132:0)
[22:42:17.351] Timer tick, asking Buildbucket for the build status
[22:42:17.410] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:17.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:133:0) after 1m0s
[22:43:17.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:133:0)
[22:43:17.440] Timer tick, asking Buildbucket for the build status
[22:43:17.501] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:17.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:134:0) after 1m0s
[22:44:17.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:134:0)
[22:44:17.522] Timer tick, asking Buildbucket for the build status
[22:44:17.580] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:17.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:135:0) after 1m0s
[22:45:17.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:135:0)
[22:45:17.695] Timer tick, asking Buildbucket for the build status
[22:45:17.737] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:17.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:136:0) after 1m0s
[22:46:17.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:136:0)
[22:46:17.756] Timer tick, asking Buildbucket for the build status
[22:46:17.815] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:17.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:137:0) after 1m0s
[22:47:17.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:137:0)
[22:47:17.877] Timer tick, asking Buildbucket for the build status
[22:47:17.981] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:17.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:138:0) after 1m0s
[22:48:18.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:138:0)
[22:48:18.200] Timer tick, asking Buildbucket for the build status
[22:48:18.243] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:18.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:139:0) after 1m0s
[22:49:18.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:139:0)
[22:49:18.266] Timer tick, asking Buildbucket for the build status
[22:49:18.491] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:18.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:140:0) after 1m0s
[22:50:18.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:140:0)
[22:50:18.511] Timer tick, asking Buildbucket for the build status
[22:50:18.781] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:18.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:141:0) after 1m0s
[22:51:18.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:141:0)
[22:51:18.830] Timer tick, asking Buildbucket for the build status
[22:51:18.951] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:18.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:142:0) after 1m0s
[22:52:18.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:142:0)
[22:52:18.971] Timer tick, asking Buildbucket for the build status
[22:52:19.012] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:19.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:143:0) after 1m0s
[22:53:19.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:143:0)
[22:53:19.034] Timer tick, asking Buildbucket for the build status
[22:53:19.162] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:19.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:144:0) after 1m0s
[22:54:19.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:144:0)
[22:54:19.191] Timer tick, asking Buildbucket for the build status
[22:54:19.231] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:19.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:145:0) after 1m0s
[22:55:19.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:145:0)
[22:55:19.251] Timer tick, asking Buildbucket for the build status
[22:55:19.294] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:19.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:146:0) after 1m0s
[22:56:19.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:146:0)
[22:56:19.316] Timer tick, asking Buildbucket for the build status
[22:56:19.357] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:19.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:147:0) after 1m0s
[22:57:19.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:147:0)
[22:57:19.376] Timer tick, asking Buildbucket for the build status
[22:57:19.419] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:19.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:148:0) after 1m0s
[22:58:19.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:148:0)
[22:58:19.439] Timer tick, asking Buildbucket for the build status
[22:58:19.577] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:19.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:149:0) after 1m0s
[22:59:19.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:149:0)
[22:59:19.599] Timer tick, asking Buildbucket for the build status
[22:59:19.681] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:19.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:150:0) after 1m0s
[23:00:19.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:150:0)
[23:00:19.700] Timer tick, asking Buildbucket for the build status
[23:00:19.743] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:19.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:151:0) after 1m0s
[23:01:19.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:151:0)
[23:01:19.774] Timer tick, asking Buildbucket for the build status
[23:01:19.818] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:19.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:152:0) after 1m0s
[23:02:19.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:152:0)
[23:02:19.842] Timer tick, asking Buildbucket for the build status
[23:02:19.962] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:19.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:153:0) after 1m0s
[23:03:19.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:153:0)
[23:03:19.984] Timer tick, asking Buildbucket for the build status
[23:03:20.134] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:20.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:154:0) after 1m0s
[23:04:20.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:154:0)
[23:04:20.155] Timer tick, asking Buildbucket for the build status
[23:04:20.196] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:20.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:155:0) after 1m0s
[23:05:20.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:155:0)
[23:05:20.239] Timer tick, asking Buildbucket for the build status
[23:05:20.290] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:20.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:156:0) after 1m0s
[23:06:20.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:156:0)
[23:06:20.319] Timer tick, asking Buildbucket for the build status
[23:06:20.360] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:20.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:157:0) after 1m0s
[23:07:20.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:157:0)
[23:07:20.380] Timer tick, asking Buildbucket for the build status
[23:07:20.465] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:20.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:158:0) after 1m0s
[23:08:20.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:158:0)
[23:08:20.503] Timer tick, asking Buildbucket for the build status
[23:08:20.584] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:20.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:159:0) after 1m0s
[23:09:20.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:159:0)
[23:09:20.613] Timer tick, asking Buildbucket for the build status
[23:09:20.754] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:20.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:160:0) after 1m0s
[23:10:20.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:160:0)
[23:10:20.795] Timer tick, asking Buildbucket for the build status
[23:10:20.857] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:20.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:161:0) after 1m0s
[23:11:20.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:161:0)
[23:11:20.916] Timer tick, asking Buildbucket for the build status
[23:11:20.994] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:20.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:162:0) after 1m0s
[23:12:21.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:162:0)
[23:12:21.026] Timer tick, asking Buildbucket for the build status
[23:12:21.066] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:21.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:163:0) after 1m0s
[23:13:21.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:163:0)
[23:13:21.087] Timer tick, asking Buildbucket for the build status
[23:13:21.164] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:21.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:164:0) after 1m0s
[23:14:21.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:164:0)
[23:14:21.187] Timer tick, asking Buildbucket for the build status
[23:14:21.280] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:21.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:165:0) after 1m0s
[23:15:21.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:165:0)
[23:15:21.325] Timer tick, asking Buildbucket for the build status
[23:15:21.556] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:21.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:166:0) after 1m0s
[23:16:21.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:166:0)
[23:16:21.578] Timer tick, asking Buildbucket for the build status
[23:16:21.860] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:21.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:167:0) after 1m0s
[23:17:21.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:167:0)
[23:17:21.914] Timer tick, asking Buildbucket for the build status
[23:17:21.971] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:21.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:168:0) after 1m0s
[23:18:21.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:168:0)
[23:18:21.992] Timer tick, asking Buildbucket for the build status
[23:18:22.087] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:22.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:169:0) after 1m0s
[23:19:22.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:169:0)
[23:19:22.096] Timer tick, asking Buildbucket for the build status
[23:19:22.256] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:22.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:170:0) after 1m0s
[23:20:22.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:170:0)
[23:20:22.280] Timer tick, asking Buildbucket for the build status
[23:20:22.387] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:22.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:171:0) after 1m0s
[23:21:22.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:171:0)
[23:21:22.428] Timer tick, asking Buildbucket for the build status
[23:21:22.480] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:22.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:172:0) after 1m0s
[23:22:22.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:172:0)
[23:22:22.510] Timer tick, asking Buildbucket for the build status
[23:22:22.681] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:22.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:173:0) after 1m0s
[23:23:22.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:173:0)
[23:23:22.716] Timer tick, asking Buildbucket for the build status
[23:23:22.898] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:22.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:174:0) after 1m0s
[23:24:22.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:174:0)
[23:24:22.921] Timer tick, asking Buildbucket for the build status
[23:24:22.962] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:22.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:175:0) after 1m0s
[23:25:22.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:175:0)
[23:25:22.986] Timer tick, asking Buildbucket for the build status
[23:25:23.025] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:23.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:176:0) after 1m0s
[23:26:23.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:176:0)
[23:26:23.055] Timer tick, asking Buildbucket for the build status
[23:26:23.097] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:23.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:177:0) after 1m0s
[23:27:23.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:177:0)
[23:27:23.115] Timer tick, asking Buildbucket for the build status
[23:27:23.157] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:23.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:178:0) after 1m0s
[23:28:23.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:178:0)
[23:28:23.199] Timer tick, asking Buildbucket for the build status
[23:28:23.345] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:23.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:179:0) after 1m0s
[23:29:23.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:179:0)
[23:29:23.371] Timer tick, asking Buildbucket for the build status
[23:29:23.417] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:23.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:180:0) after 1m0s
[23:30:23.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:180:0)
[23:30:23.438] Timer tick, asking Buildbucket for the build status
[23:30:23.573] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:23.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:181:0) after 1m0s
[23:31:23.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:181:0)
[23:31:23.599] Timer tick, asking Buildbucket for the build status
[23:31:23.908] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:23.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:182:0) after 1m0s
[23:32:23.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:182:0)
[23:32:23.929] Timer tick, asking Buildbucket for the build status
[23:32:24.022] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:24.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:183:0) after 1m0s
[23:33:24.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:183:0)
[23:33:24.114] Timer tick, asking Buildbucket for the build status
[23:33:24.192] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:24.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:184:0) after 1m0s
[23:34:24.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:184:0)
[23:34:24.219] Timer tick, asking Buildbucket for the build status
[23:34:24.300] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:24.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:185:0) after 1m0s
[23:35:24.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:185:0)
[23:35:24.325] Timer tick, asking Buildbucket for the build status
[23:35:24.583] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:24.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:186:0) after 1m0s
[23:36:24.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:186:0)
[23:36:24.604] Timer tick, asking Buildbucket for the build status
[23:36:24.642] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:24.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:187:0) after 1m0s
[23:37:24.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:187:0)
[23:37:24.666] Timer tick, asking Buildbucket for the build status
[23:37:24.756] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:24.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:188:0) after 1m0s
[23:38:24.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:188:0)
[23:38:24.786] Timer tick, asking Buildbucket for the build status
[23:38:24.892] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:24.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:189:0) after 1m0s
[23:39:24.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:189:0)
[23:39:24.911] Timer tick, asking Buildbucket for the build status
[23:39:25.003] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:25.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:190:0) after 1m0s
[23:40:25.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:190:0)
[23:40:25.045] Timer tick, asking Buildbucket for the build status
[23:40:25.080] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:25.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:191:0) after 1m0s
[23:41:25.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:191:0)
[23:41:25.101] Timer tick, asking Buildbucket for the build status
[23:41:25.279] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:25.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:192:0) after 1m0s
[23:42:25.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:192:0)
[23:42:25.308] Timer tick, asking Buildbucket for the build status
[23:42:25.424] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:25.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:193:0) after 1m0s
[23:43:25.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:193:0)
[23:43:25.445] Timer tick, asking Buildbucket for the build status
[23:43:25.541] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:25.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:194:0) after 1m0s
[23:44:25.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:194:0)
[23:44:25.562] Timer tick, asking Buildbucket for the build status
[23:44:25.787] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:25.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:195:0) after 1m0s
[23:45:25.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:195:0)
[23:45:25.812] Timer tick, asking Buildbucket for the build status
[23:45:25.918] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:25.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:196:0) after 1m0s
[23:46:25.961] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:196:0)
[23:46:25.961] Timer tick, asking Buildbucket for the build status
[23:46:26.157] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:26.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:197:0) after 1m0s
[23:47:26.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:197:0)
[23:47:26.182] Timer tick, asking Buildbucket for the build status
[23:47:26.395] Build 8921516065385504288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:26.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9086946127970201984:198:0) after 1m0s
[23:47:57.104] Received PubSub notification, asking Buildbucket for the build status
[23:47:57.144] Build 8921516065385504288: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:47:57.144] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1550188076098720",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550176287244074",
"failure_reason": "BUILD_FAILURE",
"id": "8921516065385504288",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4098-34330fc\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-141h\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure annotated steps\\nFailure torture_tests_clang arm\\nFailure llvm-test-suite arm --opt O3f --opt O2b\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 4700, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"linux-pnacl-x86_64-tests-arm\", \"key\": \"builder\"}, {\"value\": \"commit/git/15362ac60573769f6606ab1d1c11668052e411a7\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7\", \"key\": \"buildset\"}, {\"value\": \"9086946127970201984\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"nacl/linux-pnacl-x86_64-tests-arm\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"nacl\", \"builder\": \"linux-pnacl-x86_64-tests-arm\", \"bucket\": \"toolchain\"}, \"number\": 4700, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"85e01437f26ff37dc358ef303d841313756fe1f8\"}, \"recipe\": {\"name\": \"nacl\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921516065385504288\", \"input\": {\"gitilesCommit\": {\"project\": \"native_client/src/native_client\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}, \"createTime\": \"2019-02-14T20:31:27.244074Z\"}}, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-arm\", \"got_revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550176287244074, \"tags\": [\"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4700\", \"builder:linux-pnacl-x86_64-tests-arm\", \"buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086946127970201984\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8921516065385504288\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-141h\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550177960265098",
"status": "COMPLETED",
"status_changed_ts": "1550188076571465",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4700",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086946127970201984",
"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/8921516065385504288/+/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:4306868397a00510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550188076573442",
"url": "https://ci.chromium.org/b/8921516065385504288",
"utcnow_ts": "1550188077135430"
}
}
[23:47:57.144] Invocation finished in 3h16m31.749185291s with status FAILED