[20:04:23.267] New invocation is queued and will start shortly
[20:04:24.453] Starting the invocation (attempt 1)
[20:04:24.519] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:04:24.520] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:04:24.520] Buildbucket request:
{
"bucket": "luci.nacl.ci",
"client_operation_id": "9024073534237769840",
"parameters_json": "{\"builder_name\":\"linux_64-newlib-arm_qemu-pnacl-opt\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\",\"title\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"scheduler_invocation_id:9024073534237769840",
"scheduler_job_id:nacl/linux_64-newlib-arm_qemu-pnacl-opt",
"user_agent:luci-scheduler",
"buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"gitiles_ref:refs/heads/master"
]
}
[20:04:26.569] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1610136264651601",
"id": "8858643472116019536",
"parameters_json": "{\"builder_name\": \"linux_64-newlib-arm_qemu-pnacl-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610136266381747",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/166",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024073534237769840",
"scheduler_job_id:nacl/linux_64-newlib-arm_qemu-pnacl-opt",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8858643472116019536/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1610136266381927",
"url": "https://ci.chromium.org/b/8858643472116019536",
"utcnow_ts": "1610136266558603"
}
}
[20:04:26.569] Task URL: https://ci.chromium.org/b/8858643472116019536
[20:04:26.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:2:0) after 1m0s
[20:05:26.924] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:2:0)
[20:05:26.924] Timer tick, asking Buildbucket for the build status
[20:05:27.517] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:27.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:3:0) after 1m0s
[20:06:27.659] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:3:0)
[20:06:27.659] Timer tick, asking Buildbucket for the build status
[20:06:27.825] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:27.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:4:0) after 1m0s
[20:07:28.607] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:4:0)
[20:07:28.607] Timer tick, asking Buildbucket for the build status
[20:07:28.697] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:28.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:5:0) after 1m0s
[20:08:29.201] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:5:0)
[20:08:29.201] Timer tick, asking Buildbucket for the build status
[20:08:29.427] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:29.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:6:0) after 1m0s
[20:09:29.691] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:6:0)
[20:09:29.691] Timer tick, asking Buildbucket for the build status
[20:09:29.781] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:29.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:7:0) after 1m0s
[20:10:29.913] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:7:0)
[20:10:29.913] Timer tick, asking Buildbucket for the build status
[20:10:30.033] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:30.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:8:0) after 1m0s
[20:11:30.068] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:8:0)
[20:11:30.068] Timer tick, asking Buildbucket for the build status
[20:11:30.315] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:30.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:9:0) after 1m0s
[20:12:30.338] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:9:0)
[20:12:30.338] Timer tick, asking Buildbucket for the build status
[20:12:30.463] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:30.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:10:0) after 1m0s
[20:13:30.654] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:10:0)
[20:13:30.654] Timer tick, asking Buildbucket for the build status
[20:13:30.762] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:30.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:11:0) after 1m0s
[20:14:30.885] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:11:0)
[20:14:30.885] Timer tick, asking Buildbucket for the build status
[20:14:31.053] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:31.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:12:0) after 1m0s
[20:15:31.516] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:12:0)
[20:15:31.516] Timer tick, asking Buildbucket for the build status
[20:15:31.742] Build 8858643472116019536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:31.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:13:0) after 1m0s
[20:15:51.157] Received PubSub notification, asking Buildbucket for the build status
[20:15:51.234] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:31.901] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:13:0)
[20:16:31.901] Timer tick, asking Buildbucket for the build status
[20:16:32.005] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:32.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:15:0) after 1m0s
[20:17:32.125] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:15:0)
[20:17:32.125] Timer tick, asking Buildbucket for the build status
[20:17:32.233] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:32.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:16:0) after 1m0s
[20:18:32.368] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:16:0)
[20:18:32.368] Timer tick, asking Buildbucket for the build status
[20:18:32.458] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:32.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:17:0) after 1m0s
[20:19:32.555] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:17:0)
[20:19:32.555] Timer tick, asking Buildbucket for the build status
[20:19:32.667] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:32.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:18:0) after 1m0s
[20:20:32.776] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:18:0)
[20:20:32.776] Timer tick, asking Buildbucket for the build status
[20:20:32.865] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:32.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:19:0) after 1m0s
[20:21:32.930] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:19:0)
[20:21:32.930] Timer tick, asking Buildbucket for the build status
[20:21:32.996] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:32.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:20:0) after 1m0s
[20:22:33.141] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:20:0)
[20:22:33.141] Timer tick, asking Buildbucket for the build status
[20:22:33.220] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:33.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:21:0) after 1m0s
[20:23:33.254] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:21:0)
[20:23:33.254] Timer tick, asking Buildbucket for the build status
[20:23:33.332] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:33.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:22:0) after 1m0s
[20:24:33.358] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:22:0)
[20:24:33.358] Timer tick, asking Buildbucket for the build status
[20:24:33.524] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:33.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:23:0) after 1m0s
[20:25:33.594] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:23:0)
[20:25:33.594] Timer tick, asking Buildbucket for the build status
[20:25:33.723] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:33.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:24:0) after 1m0s
[20:26:33.712] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:24:0)
[20:26:33.712] Timer tick, asking Buildbucket for the build status
[20:26:33.822] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:33.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:25:0) after 1m0s
[20:27:33.882] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:25:0)
[20:27:33.882] Timer tick, asking Buildbucket for the build status
[20:27:33.964] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:33.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:26:0) after 1m0s
[20:28:34.409] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:26:0)
[20:28:34.409] Timer tick, asking Buildbucket for the build status
[20:28:34.515] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:34.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:27:0) after 1m0s
[20:29:34.696] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:27:0)
[20:29:34.696] Timer tick, asking Buildbucket for the build status
[20:29:34.925] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:34.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:28:0) after 1m0s
[20:30:34.957] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:28:0)
[20:30:34.957] Timer tick, asking Buildbucket for the build status
[20:30:35.092] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:35.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:29:0) after 1m0s
[20:31:35.238] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:29:0)
[20:31:35.238] Timer tick, asking Buildbucket for the build status
[20:31:35.419] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:35.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:30:0) after 1m0s
[20:32:35.584] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:30:0)
[20:32:35.584] Timer tick, asking Buildbucket for the build status
[20:32:35.690] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:35.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:31:0) after 1m0s
[20:33:35.740] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:31:0)
[20:33:35.740] Timer tick, asking Buildbucket for the build status
[20:33:35.861] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:35.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:32:0) after 1m0s
[20:34:35.909] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:32:0)
[20:34:35.909] Timer tick, asking Buildbucket for the build status
[20:34:36.072] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:36.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:33:0) after 1m0s
[20:35:36.121] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:33:0)
[20:35:36.121] Timer tick, asking Buildbucket for the build status
[20:35:36.192] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:36.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:34:0) after 1m0s
[20:36:36.320] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:34:0)
[20:36:36.320] Timer tick, asking Buildbucket for the build status
[20:36:36.406] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:36.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:35:0) after 1m0s
[20:37:36.454] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:35:0)
[20:37:36.454] Timer tick, asking Buildbucket for the build status
[20:37:36.836] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:36.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:36:0) after 1m0s
[20:38:36.870] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:36:0)
[20:38:36.870] Timer tick, asking Buildbucket for the build status
[20:38:36.998] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:36.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:37:0) after 1m0s
[20:39:37.041] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:37:0)
[20:39:37.041] Timer tick, asking Buildbucket for the build status
[20:39:37.131] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:37.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:38:0) after 1m0s
[20:40:37.212] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:38:0)
[20:40:37.212] Timer tick, asking Buildbucket for the build status
[20:40:37.341] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:37.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:39:0) after 1m0s
[20:41:37.485] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:39:0)
[20:41:37.485] Timer tick, asking Buildbucket for the build status
[20:41:37.592] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:37.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:40:0) after 1m0s
[20:42:37.643] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:40:0)
[20:42:37.643] Timer tick, asking Buildbucket for the build status
[20:42:39.381] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:39.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:41:0) after 1m0s
[20:43:39.425] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:41:0)
[20:43:39.425] Timer tick, asking Buildbucket for the build status
[20:43:39.532] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:39.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:42:0) after 1m0s
[20:44:39.538] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:42:0)
[20:44:39.538] Timer tick, asking Buildbucket for the build status
[20:44:39.902] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:39.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:43:0) after 1m0s
[20:45:40.017] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:43:0)
[20:45:40.017] Timer tick, asking Buildbucket for the build status
[20:45:40.336] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:40.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:44:0) after 1m0s
[20:46:40.387] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:44:0)
[20:46:40.387] Timer tick, asking Buildbucket for the build status
[20:46:40.523] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:40.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:45:0) after 1m0s
[20:47:40.621] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:45:0)
[20:47:40.621] Timer tick, asking Buildbucket for the build status
[20:47:40.893] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:40.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:46:0) after 1m0s
[20:48:40.989] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:46:0)
[20:48:40.989] Timer tick, asking Buildbucket for the build status
[20:48:41.103] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:41.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:47:0) after 1m0s
[20:49:41.087] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:47:0)
[20:49:41.087] Timer tick, asking Buildbucket for the build status
[20:49:41.244] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:41.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:48:0) after 1m0s
[20:50:41.288] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:48:0)
[20:50:41.288] Timer tick, asking Buildbucket for the build status
[20:50:41.648] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:41.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:49:0) after 1m0s
[20:51:41.677] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:49:0)
[20:51:41.677] Timer tick, asking Buildbucket for the build status
[20:51:42.007] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:42.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:50:0) after 1m0s
[20:52:42.068] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:50:0)
[20:52:42.068] Timer tick, asking Buildbucket for the build status
[20:52:42.440] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:42.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:51:0) after 1m0s
[20:53:42.509] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:51:0)
[20:53:42.509] Timer tick, asking Buildbucket for the build status
[20:53:42.897] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:42.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:52:0) after 1m0s
[20:54:42.921] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:52:0)
[20:54:42.921] Timer tick, asking Buildbucket for the build status
[20:54:43.338] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:43.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:53:0) after 1m0s
[20:55:43.341] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:53:0)
[20:55:43.341] Timer tick, asking Buildbucket for the build status
[20:55:43.418] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:43.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:54:0) after 1m0s
[20:56:43.461] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:54:0)
[20:56:43.461] Timer tick, asking Buildbucket for the build status
[20:56:43.529] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:43.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:55:0) after 1m0s
[20:57:43.599] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:55:0)
[20:57:43.599] Timer tick, asking Buildbucket for the build status
[20:57:43.681] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:43.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:56:0) after 1m0s
[20:58:43.707] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:56:0)
[20:58:43.707] Timer tick, asking Buildbucket for the build status
[20:58:43.786] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:43.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:57:0) after 1m0s
[20:59:43.823] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:57:0)
[20:59:43.823] Timer tick, asking Buildbucket for the build status
[20:59:43.953] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:43.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:58:0) after 1m0s
[21:00:44.023] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:58:0)
[21:00:44.023] Timer tick, asking Buildbucket for the build status
[21:00:44.159] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:44.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:59:0) after 1m0s
[21:01:44.194] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:59:0)
[21:01:44.194] Timer tick, asking Buildbucket for the build status
[21:01:44.509] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:44.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:60:0) after 1m0s
[21:02:44.550] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:60:0)
[21:02:44.550] Timer tick, asking Buildbucket for the build status
[21:02:44.697] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:44.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:61:0) after 1m0s
[21:03:44.746] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:61:0)
[21:03:44.746] Timer tick, asking Buildbucket for the build status
[21:03:45.097] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:45.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:62:0) after 1m0s
[21:04:45.138] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:62:0)
[21:04:45.138] Timer tick, asking Buildbucket for the build status
[21:04:45.387] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:45.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:63:0) after 1m0s
[21:05:45.690] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:63:0)
[21:05:45.690] Timer tick, asking Buildbucket for the build status
[21:05:45.885] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:45.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:64:0) after 1m0s
[21:06:46.210] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:64:0)
[21:06:46.210] Timer tick, asking Buildbucket for the build status
[21:06:46.374] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:46.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:65:0) after 1m0s
[21:07:46.465] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:65:0)
[21:07:46.465] Timer tick, asking Buildbucket for the build status
[21:07:46.602] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:46.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:66:0) after 1m0s
[21:08:46.649] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:66:0)
[21:08:46.649] Timer tick, asking Buildbucket for the build status
[21:08:46.759] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:46.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:67:0) after 1m0s
[21:09:46.831] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:67:0)
[21:09:46.831] Timer tick, asking Buildbucket for the build status
[21:09:47.229] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:47.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:68:0) after 1m0s
[21:10:47.252] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:68:0)
[21:10:47.252] Timer tick, asking Buildbucket for the build status
[21:10:47.347] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:47.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:69:0) after 1m0s
[21:11:47.485] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:69:0)
[21:11:47.485] Timer tick, asking Buildbucket for the build status
[21:11:47.695] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:47.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:70:0) after 1m0s
[21:12:47.743] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:70:0)
[21:12:47.743] Timer tick, asking Buildbucket for the build status
[21:12:47.822] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:47.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:71:0) after 1m0s
[21:13:48.228] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:71:0)
[21:13:48.228] Timer tick, asking Buildbucket for the build status
[21:13:49.331] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:49.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:72:0) after 1m0s
[21:14:49.386] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:72:0)
[21:14:49.386] Timer tick, asking Buildbucket for the build status
[21:14:49.496] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:49.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:73:0) after 1m0s
[21:15:49.738] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:73:0)
[21:15:49.738] Timer tick, asking Buildbucket for the build status
[21:15:49.817] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:49.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:74:0) after 1m0s
[21:16:49.868] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:74:0)
[21:16:49.868] Timer tick, asking Buildbucket for the build status
[21:16:49.938] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:49.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:75:0) after 1m0s
[21:17:49.960] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:75:0)
[21:17:49.960] Timer tick, asking Buildbucket for the build status
[21:17:50.201] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:50.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:76:0) after 1m0s
[21:18:50.572] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:76:0)
[21:18:50.572] Timer tick, asking Buildbucket for the build status
[21:18:50.627] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:50.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:77:0) after 1m0s
[21:19:50.930] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:77:0)
[21:19:50.930] Timer tick, asking Buildbucket for the build status
[21:19:51.293] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:51.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:78:0) after 1m0s
[21:20:51.356] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:78:0)
[21:20:51.356] Timer tick, asking Buildbucket for the build status
[21:20:51.414] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:51.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:79:0) after 1m0s
[21:21:51.568] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:79:0)
[21:21:51.568] Timer tick, asking Buildbucket for the build status
[21:21:51.622] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:51.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:80:0) after 1m0s
[21:22:51.721] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:80:0)
[21:22:51.721] Timer tick, asking Buildbucket for the build status
[21:22:51.808] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:51.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:81:0) after 1m0s
[21:23:52.229] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:81:0)
[21:23:52.229] Timer tick, asking Buildbucket for the build status
[21:23:52.287] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:52.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:82:0) after 1m0s
[21:24:52.366] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:82:0)
[21:24:52.366] Timer tick, asking Buildbucket for the build status
[21:24:52.464] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:52.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:83:0) after 1m0s
[21:25:52.776] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:83:0)
[21:25:52.776] Timer tick, asking Buildbucket for the build status
[21:25:52.920] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:52.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:84:0) after 1m0s
[21:26:52.924] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:84:0)
[21:26:52.924] Timer tick, asking Buildbucket for the build status
[21:26:53.061] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:53.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:85:0) after 1m0s
[21:27:53.165] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:85:0)
[21:27:53.165] Timer tick, asking Buildbucket for the build status
[21:27:53.333] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:53.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:86:0) after 1m0s
[21:28:53.332] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:86:0)
[21:28:53.332] Timer tick, asking Buildbucket for the build status
[21:28:53.405] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:53.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:87:0) after 1m0s
[21:29:53.408] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:87:0)
[21:29:53.408] Timer tick, asking Buildbucket for the build status
[21:29:53.513] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:53.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:88:0) after 1m0s
[21:30:53.512] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:88:0)
[21:30:53.512] Timer tick, asking Buildbucket for the build status
[21:30:53.868] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:53.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:89:0) after 1m0s
[21:31:53.964] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:89:0)
[21:31:53.964] Timer tick, asking Buildbucket for the build status
[21:31:54.041] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:54.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:90:0) after 1m0s
[21:32:54.116] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:90:0)
[21:32:54.116] Timer tick, asking Buildbucket for the build status
[21:32:54.207] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:54.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:91:0) after 1m0s
[21:33:54.408] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:91:0)
[21:33:54.408] Timer tick, asking Buildbucket for the build status
[21:33:54.479] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:54.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:92:0) after 1m0s
[21:34:54.690] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:92:0)
[21:34:54.690] Timer tick, asking Buildbucket for the build status
[21:34:54.853] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:54.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:93:0) after 1m0s
[21:35:54.895] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:93:0)
[21:35:54.895] Timer tick, asking Buildbucket for the build status
[21:35:55.081] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:55.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:94:0) after 1m0s
[21:36:55.129] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:94:0)
[21:36:55.129] Timer tick, asking Buildbucket for the build status
[21:36:55.221] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:55.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:95:0) after 1m0s
[21:37:55.255] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:95:0)
[21:37:55.255] Timer tick, asking Buildbucket for the build status
[21:37:55.951] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:55.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:96:0) after 1m0s
[21:38:55.985] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:96:0)
[21:38:55.985] Timer tick, asking Buildbucket for the build status
[21:38:56.124] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:56.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:97:0) after 1m0s
[21:39:56.138] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:97:0)
[21:39:56.138] Timer tick, asking Buildbucket for the build status
[21:39:56.256] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:56.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:98:0) after 1m0s
[21:40:56.278] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:98:0)
[21:40:56.278] Timer tick, asking Buildbucket for the build status
[21:40:56.460] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:56.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:99:0) after 1m0s
[21:41:56.518] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:99:0)
[21:41:56.518] Timer tick, asking Buildbucket for the build status
[21:41:57.087] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:57.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:100:0) after 1m0s
[21:42:57.186] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:100:0)
[21:42:57.186] Timer tick, asking Buildbucket for the build status
[21:42:57.419] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:57.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:101:0) after 1m0s
[21:43:57.512] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:101:0)
[21:43:57.512] Timer tick, asking Buildbucket for the build status
[21:43:57.619] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:57.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:102:0) after 1m0s
[21:44:57.701] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:102:0)
[21:44:57.701] Timer tick, asking Buildbucket for the build status
[21:44:57.825] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:57.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:103:0) after 1m0s
[21:45:57.896] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:103:0)
[21:45:57.896] Timer tick, asking Buildbucket for the build status
[21:45:58.218] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:58.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:104:0) after 1m0s
[21:46:58.241] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:104:0)
[21:46:58.241] Timer tick, asking Buildbucket for the build status
[21:46:58.322] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:58.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:105:0) after 1m0s
[21:47:58.400] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:105:0)
[21:47:58.400] Timer tick, asking Buildbucket for the build status
[21:47:58.468] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:58.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:106:0) after 1m0s
[21:48:58.544] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:106:0)
[21:48:58.544] Timer tick, asking Buildbucket for the build status
[21:48:58.605] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:58.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:107:0) after 1m0s
[21:49:58.666] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:107:0)
[21:49:58.666] Timer tick, asking Buildbucket for the build status
[21:49:58.913] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:58.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:108:0) after 1m0s
[21:50:58.983] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:108:0)
[21:50:58.983] Timer tick, asking Buildbucket for the build status
[21:50:59.045] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:59.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:109:0) after 1m0s
[21:51:59.105] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:109:0)
[21:51:59.105] Timer tick, asking Buildbucket for the build status
[21:51:59.204] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:59.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:110:0) after 1m0s
[21:52:59.308] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:110:0)
[21:52:59.308] Timer tick, asking Buildbucket for the build status
[21:52:59.386] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:59.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:111:0) after 1m0s
[21:53:59.445] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:111:0)
[21:53:59.445] Timer tick, asking Buildbucket for the build status
[21:53:59.511] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:59.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:112:0) after 1m0s
[21:54:59.526] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:112:0)
[21:54:59.526] Timer tick, asking Buildbucket for the build status
[21:54:59.733] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:59.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:113:0) after 1m0s
[21:55:59.788] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:113:0)
[21:55:59.788] Timer tick, asking Buildbucket for the build status
[21:56:00.165] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:00.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:114:0) after 1m0s
[21:57:00.260] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:114:0)
[21:57:00.260] Timer tick, asking Buildbucket for the build status
[21:57:00.337] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:00.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:115:0) after 1m0s
[21:58:00.423] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:115:0)
[21:58:00.423] Timer tick, asking Buildbucket for the build status
[21:58:00.594] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:00.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:116:0) after 1m0s
[21:59:01.370] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:116:0)
[21:59:01.370] Timer tick, asking Buildbucket for the build status
[21:59:01.523] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:01.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:117:0) after 1m0s
[22:00:01.615] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:117:0)
[22:00:01.615] Timer tick, asking Buildbucket for the build status
[22:00:02.474] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:02.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:118:0) after 1m0s
[22:01:02.564] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:118:0)
[22:01:02.564] Timer tick, asking Buildbucket for the build status
[22:01:02.663] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:02.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:119:0) after 1m0s
[22:02:02.869] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:119:0)
[22:02:02.869] Timer tick, asking Buildbucket for the build status
[22:02:03.054] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:03.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:120:0) after 1m0s
[22:03:03.300] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:120:0)
[22:03:03.300] Timer tick, asking Buildbucket for the build status
[22:03:03.432] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:03.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:121:0) after 1m0s
[22:04:03.499] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:121:0)
[22:04:03.499] Timer tick, asking Buildbucket for the build status
[22:04:03.713] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:03.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:122:0) after 1m0s
[22:05:03.713] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:122:0)
[22:05:03.713] Timer tick, asking Buildbucket for the build status
[22:05:03.824] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:03.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:123:0) after 1m0s
[22:06:03.909] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:123:0)
[22:06:03.909] Timer tick, asking Buildbucket for the build status
[22:06:03.969] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:03.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:124:0) after 1m0s
[22:07:04.038] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:124:0)
[22:07:04.038] Timer tick, asking Buildbucket for the build status
[22:07:04.412] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:04.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:125:0) after 1m0s
[22:08:04.472] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:125:0)
[22:08:04.472] Timer tick, asking Buildbucket for the build status
[22:08:04.937] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:04.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:126:0) after 1m0s
[22:09:04.961] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:126:0)
[22:09:04.961] Timer tick, asking Buildbucket for the build status
[22:09:05.024] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:05.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:127:0) after 1m0s
[22:10:05.039] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:127:0)
[22:10:05.039] Timer tick, asking Buildbucket for the build status
[22:10:06.433] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:06.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:128:0) after 1m0s
[22:11:06.633] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:128:0)
[22:11:06.633] Timer tick, asking Buildbucket for the build status
[22:11:06.878] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:06.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:129:0) after 1m0s
[22:12:07.145] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:129:0)
[22:12:07.145] Timer tick, asking Buildbucket for the build status
[22:12:07.424] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:07.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:130:0) after 1m0s
[22:13:07.479] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:130:0)
[22:13:07.479] Timer tick, asking Buildbucket for the build status
[22:13:07.560] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:07.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:131:0) after 1m0s
[22:14:07.734] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:131:0)
[22:14:07.734] Timer tick, asking Buildbucket for the build status
[22:14:07.916] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:07.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:132:0) after 1m0s
[22:15:08.128] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:132:0)
[22:15:08.128] Timer tick, asking Buildbucket for the build status
[22:15:08.460] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:08.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:133:0) after 1m0s
[22:16:08.583] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:133:0)
[22:16:08.583] Timer tick, asking Buildbucket for the build status
[22:16:08.678] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:08.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:134:0) after 1m0s
[22:17:08.792] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:134:0)
[22:17:08.792] Timer tick, asking Buildbucket for the build status
[22:17:09.249] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:09.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:135:0) after 1m0s
[22:18:09.311] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:135:0)
[22:18:09.311] Timer tick, asking Buildbucket for the build status
[22:18:09.418] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:09.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:136:0) after 1m0s
[22:19:09.521] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:136:0)
[22:19:09.521] Timer tick, asking Buildbucket for the build status
[22:19:09.721] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:09.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:137:0) after 1m0s
[22:20:09.793] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:137:0)
[22:20:09.793] Timer tick, asking Buildbucket for the build status
[22:20:09.906] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:09.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:138:0) after 1m0s
[22:21:09.952] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:138:0)
[22:21:09.952] Timer tick, asking Buildbucket for the build status
[22:21:10.025] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:10.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:139:0) after 1m0s
[22:22:10.059] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:139:0)
[22:22:10.059] Timer tick, asking Buildbucket for the build status
[22:22:10.257] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:10.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:140:0) after 1m0s
[22:23:10.304] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:140:0)
[22:23:10.304] Timer tick, asking Buildbucket for the build status
[22:23:10.603] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:10.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:141:0) after 1m0s
[22:24:10.622] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:141:0)
[22:24:10.622] Timer tick, asking Buildbucket for the build status
[22:24:10.988] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:10.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:142:0) after 1m0s
[22:25:11.084] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:142:0)
[22:25:11.084] Timer tick, asking Buildbucket for the build status
[22:25:11.271] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:11.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:143:0) after 1m0s
[22:26:11.429] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:143:0)
[22:26:11.429] Timer tick, asking Buildbucket for the build status
[22:26:11.529] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:11.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:144:0) after 1m0s
[22:27:11.595] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:144:0)
[22:27:11.595] Timer tick, asking Buildbucket for the build status
[22:27:11.670] Build 8858643472116019536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:11.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9024073534237769840:145:0) after 1m0s
[22:28:08.951] Received PubSub notification, asking Buildbucket for the build status
[22:28:09.062] Build 8858643472116019536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:28:09.062] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"completed_ts": "1610144888172845",
"created_by": "project:nacl",
"created_ts": "1610136264651601",
"id": "8858643472116019536",
"parameters_json": "{\"builder_name\": \"linux_64-newlib-arm_qemu-pnacl-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
"project": "nacl",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"luci-flex-ci-xenial-2-ormc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl\", \"buildername\": \"linux_64-newlib-arm_qemu-pnacl-opt\", \"buildnumber\": 166, \"got_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0c2213e2471b4804ce7cc02c147d9769f9d07f20fd3fd447c453f4900380938c_v2\", \"builder_61fc32c3bc5a7ee2a26f46d61db55ba1f83c918285cc8d2dc6086b96201d8c90_v2\", \"builder_7400dfb32d29ad49c88b7f88800b69b6d930e3bb5ad8d0eecfbdfeb7918fe3eb_v2\", \"builder_ade509247dd25059f1eaa141a5cb597841f876685ca9aeb8535a3c7aae6fce13_v2\", \"builder_c9b11021af4c3f778fe2637ff524e9c8de1affab7b075f818d9065ab0536ee22_v2\", \"git\", \"gocache\", \"goma_v2\", \"gopath\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-flex-ci-xenial-2-ormc\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610136947459647",
"status": "COMPLETED",
"status_changed_ts": "1610144888606584",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/166",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024073534237769840",
"scheduler_job_id:nacl/linux_64-newlib-arm_qemu-pnacl-opt",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8858643472116019536/+/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:50fc6b3782882510",
"user_agent:luci-scheduler"
],
"updated_ts": "1610144888620680",
"url": "https://ci.chromium.org/b/8858643472116019536",
"utcnow_ts": "1610144889049964"
}
}
[22:28:09.062] Invocation finished in 2h23m45.817880655s with status SUCCEEDED