[09:31:33.268] New invocation is queued and will start shortly
[09:31:34.691] Starting the invocation (attempt 1)
[09:31:34.739] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:31:34.740] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:31:34.740] Buildbucket request:
{
"bucket": "luci.nacl.ci",
"client_operation_id": "9020308276069736896",
"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\":\"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4ad2ff292145e8613ec371302875f0eaf8b4fa14\",\"title\":\"4ad2ff292145e8613ec371302875f0eaf8b4fa14\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}}",
"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:9020308276069736896",
"scheduler_job_id:nacl/linux_64-newlib-arm_qemu-pnacl-opt",
"user_agent:luci-scheduler",
"buildset:commit/git/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"gitiles_ref:refs/heads/master"
]
}
[09:31:35.726] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:nacl",
"created_ts": "1613727094834030",
"id": "8854878213765989408",
"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\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"title\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613727095334228",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/175",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020308276069736896",
"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/8854878213765989408/+/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": "1613727095334352",
"url": "https://ci.chromium.org/b/8854878213765989408",
"utcnow_ts": "1613727095716285"
}
}
[09:31:35.726] Task URL: https://ci.chromium.org/b/8854878213765989408
[09:31:35.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:2:0) after 1m0s
[09:32:36.380] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:2:0)
[09:32:36.380] Timer tick, asking Buildbucket for the build status
[09:32:36.497] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:36.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:3:0) after 1m0s
[09:33:36.639] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:3:0)
[09:33:36.639] Timer tick, asking Buildbucket for the build status
[09:33:36.704] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:36.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:4:0) after 1m0s
[09:34:36.775] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:4:0)
[09:34:36.775] Timer tick, asking Buildbucket for the build status
[09:34:36.841] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:36.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:5:0) after 1m0s
[09:35:37.062] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:5:0)
[09:35:37.062] Timer tick, asking Buildbucket for the build status
[09:35:37.178] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:37.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:6:0) after 1m0s
[09:36:37.340] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:6:0)
[09:36:37.340] Timer tick, asking Buildbucket for the build status
[09:36:37.547] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:37.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:7:0) after 1m0s
[09:37:37.632] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:7:0)
[09:37:37.632] Timer tick, asking Buildbucket for the build status
[09:37:37.722] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:37.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:8:0) after 1m0s
[09:38:37.779] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:8:0)
[09:38:37.779] Timer tick, asking Buildbucket for the build status
[09:38:37.847] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:37.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:9:0) after 1m0s
[09:39:37.938] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:9:0)
[09:39:37.938] Timer tick, asking Buildbucket for the build status
[09:39:38.107] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:38.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:10:0) after 1m0s
[09:40:38.205] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:10:0)
[09:40:38.205] Timer tick, asking Buildbucket for the build status
[09:40:38.421] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:38.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:11:0) after 1m0s
[09:41:38.617] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:11:0)
[09:41:38.618] Timer tick, asking Buildbucket for the build status
[09:41:38.721] Build 8854878213765989408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:38.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:12:0) after 1m0s
[09:42:30.107] Received PubSub notification, asking Buildbucket for the build status
[09:42:30.180] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:38.725] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:12:0)
[09:42:38.725] Timer tick, asking Buildbucket for the build status
[09:42:38.804] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:38.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:14:0) after 1m0s
[09:43:38.918] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:14:0)
[09:43:38.918] Timer tick, asking Buildbucket for the build status
[09:43:39.049] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:39.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:15:0) after 1m0s
[09:44:39.245] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:15:0)
[09:44:39.245] Timer tick, asking Buildbucket for the build status
[09:44:39.371] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:39.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:16:0) after 1m0s
[09:45:39.994] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:16:0)
[09:45:39.995] Timer tick, asking Buildbucket for the build status
[09:45:40.119] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:40.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:17:0) after 1m0s
[09:46:40.355] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:17:0)
[09:46:40.355] Timer tick, asking Buildbucket for the build status
[09:46:40.467] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:40.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:18:0) after 1m0s
[09:47:40.722] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:18:0)
[09:47:40.722] Timer tick, asking Buildbucket for the build status
[09:47:40.803] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:40.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:19:0) after 1m0s
[09:48:40.833] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:19:0)
[09:48:40.833] Timer tick, asking Buildbucket for the build status
[09:48:40.928] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:40.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:20:0) after 1m0s
[09:49:41.103] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:20:0)
[09:49:41.103] Timer tick, asking Buildbucket for the build status
[09:49:41.179] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:41.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:21:0) after 1m0s
[09:50:41.327] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:21:0)
[09:50:41.327] Timer tick, asking Buildbucket for the build status
[09:50:41.511] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:22:0) after 1m0s
[09:51:41.610] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:22:0)
[09:51:41.610] Timer tick, asking Buildbucket for the build status
[09:51:42.164] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:42.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:23:0) after 1m0s
[09:52:42.176] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:23:0)
[09:52:42.176] Timer tick, asking Buildbucket for the build status
[09:52:42.331] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:42.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:24:0) after 1m0s
[09:53:42.350] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:24:0)
[09:53:42.350] Timer tick, asking Buildbucket for the build status
[09:53:42.418] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:42.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:25:0) after 1m0s
[09:54:42.471] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:25:0)
[09:54:42.471] Timer tick, asking Buildbucket for the build status
[09:54:42.556] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:42.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:26:0) after 1m0s
[09:55:42.632] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:26:0)
[09:55:42.632] Timer tick, asking Buildbucket for the build status
[09:55:42.706] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:42.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:27:0) after 1m0s
[09:56:43.346] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:27:0)
[09:56:43.346] Timer tick, asking Buildbucket for the build status
[09:56:43.438] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:43.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:28:0) after 1m0s
[09:57:43.581] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:28:0)
[09:57:43.581] Timer tick, asking Buildbucket for the build status
[09:57:43.657] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:43.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:29:0) after 1m0s
[09:58:43.814] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:29:0)
[09:58:43.814] Timer tick, asking Buildbucket for the build status
[09:58:43.886] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:43.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:30:0) after 1m0s
[09:59:44.054] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:30:0)
[09:59:44.054] Timer tick, asking Buildbucket for the build status
[09:59:44.123] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:44.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:31:0) after 1m0s
[10:00:44.506] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:31:0)
[10:00:44.506] Timer tick, asking Buildbucket for the build status
[10:00:44.606] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:44.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:32:0) after 1m0s
[10:01:44.737] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:32:0)
[10:01:44.737] Timer tick, asking Buildbucket for the build status
[10:01:44.835] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:44.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:33:0) after 1m0s
[10:02:44.919] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:33:0)
[10:02:44.919] Timer tick, asking Buildbucket for the build status
[10:02:45.199] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:45.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:34:0) after 1m0s
[10:03:45.317] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:34:0)
[10:03:45.317] Timer tick, asking Buildbucket for the build status
[10:03:45.384] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:45.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:35:0) after 1m0s
[10:04:45.493] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:35:0)
[10:04:45.493] Timer tick, asking Buildbucket for the build status
[10:04:45.604] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:45.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:36:0) after 1m0s
[10:05:45.759] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:36:0)
[10:05:45.759] Timer tick, asking Buildbucket for the build status
[10:05:45.837] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:45.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:37:0) after 1m0s
[10:06:46.185] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:37:0)
[10:06:46.185] Timer tick, asking Buildbucket for the build status
[10:06:46.274] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:46.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:38:0) after 1m0s
[10:07:46.395] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:38:0)
[10:07:46.395] Timer tick, asking Buildbucket for the build status
[10:07:46.865] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:46.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:39:0) after 1m0s
[10:08:47.015] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:39:0)
[10:08:47.015] Timer tick, asking Buildbucket for the build status
[10:08:47.101] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:47.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:40:0) after 1m0s
[10:09:47.290] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:40:0)
[10:09:47.290] Timer tick, asking Buildbucket for the build status
[10:09:47.365] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:47.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:41:0) after 1m0s
[10:10:47.531] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:41:0)
[10:10:47.531] Timer tick, asking Buildbucket for the build status
[10:10:48.137] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:48.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:42:0) after 1m0s
[10:11:48.343] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:42:0)
[10:11:48.343] Timer tick, asking Buildbucket for the build status
[10:11:48.433] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:48.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:43:0) after 1m0s
[10:12:48.418] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:43:0)
[10:12:48.418] Timer tick, asking Buildbucket for the build status
[10:12:48.507] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:48.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:44:0) after 1m0s
[10:13:48.548] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:44:0)
[10:13:48.548] Timer tick, asking Buildbucket for the build status
[10:13:49.020] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:49.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:45:0) after 1m0s
[10:14:49.135] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:45:0)
[10:14:49.135] Timer tick, asking Buildbucket for the build status
[10:14:49.393] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:49.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:46:0) after 1m0s
[10:15:49.475] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:46:0)
[10:15:49.475] Timer tick, asking Buildbucket for the build status
[10:15:49.635] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:49.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:47:0) after 1m0s
[10:16:49.669] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:47:0)
[10:16:49.669] Timer tick, asking Buildbucket for the build status
[10:16:49.825] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:49.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:48:0) after 1m0s
[10:17:49.968] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:48:0)
[10:17:49.968] Timer tick, asking Buildbucket for the build status
[10:17:50.051] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:50.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:49:0) after 1m0s
[10:18:50.072] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:49:0)
[10:18:50.072] Timer tick, asking Buildbucket for the build status
[10:18:50.142] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:50.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:50:0) after 1m0s
[10:19:50.231] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:50:0)
[10:19:50.231] Timer tick, asking Buildbucket for the build status
[10:19:50.446] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:50.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:51:0) after 1m0s
[10:20:50.620] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:51:0)
[10:20:50.620] Timer tick, asking Buildbucket for the build status
[10:20:50.847] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:50.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:52:0) after 1m0s
[10:21:50.875] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:52:0)
[10:21:50.875] Timer tick, asking Buildbucket for the build status
[10:21:50.967] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:50.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:53:0) after 1m0s
[10:22:50.992] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:53:0)
[10:22:50.992] Timer tick, asking Buildbucket for the build status
[10:22:51.066] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:51.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:54:0) after 1m0s
[10:23:51.120] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:54:0)
[10:23:51.120] Timer tick, asking Buildbucket for the build status
[10:23:51.205] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:51.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:55:0) after 1m0s
[10:24:51.196] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:55:0)
[10:24:51.196] Timer tick, asking Buildbucket for the build status
[10:24:51.503] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:51.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:56:0) after 1m0s
[10:25:51.595] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:56:0)
[10:25:51.595] Timer tick, asking Buildbucket for the build status
[10:25:51.668] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:51.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:57:0) after 1m0s
[10:26:51.801] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:57:0)
[10:26:51.801] Timer tick, asking Buildbucket for the build status
[10:26:51.966] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:51.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:58:0) after 1m0s
[10:27:51.994] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:58:0)
[10:27:51.994] Timer tick, asking Buildbucket for the build status
[10:27:52.440] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:52.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:59:0) after 1m0s
[10:28:52.508] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:59:0)
[10:28:52.508] Timer tick, asking Buildbucket for the build status
[10:28:52.652] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:52.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:60:0) after 1m0s
[10:29:52.689] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:60:0)
[10:29:52.689] Timer tick, asking Buildbucket for the build status
[10:29:52.772] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:52.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:61:0) after 1m0s
[10:30:52.823] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:61:0)
[10:30:52.823] Timer tick, asking Buildbucket for the build status
[10:30:53.018] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:53.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:62:0) after 1m0s
[10:31:53.042] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:62:0)
[10:31:53.042] Timer tick, asking Buildbucket for the build status
[10:31:53.155] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:53.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:63:0) after 1m0s
[10:32:53.192] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:63:0)
[10:32:53.192] Timer tick, asking Buildbucket for the build status
[10:32:53.703] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:53.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:64:0) after 1m0s
[10:33:53.750] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:64:0)
[10:33:53.750] Timer tick, asking Buildbucket for the build status
[10:33:53.872] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:53.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:65:0) after 1m0s
[10:34:53.927] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:65:0)
[10:34:53.927] Timer tick, asking Buildbucket for the build status
[10:34:54.144] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:54.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:66:0) after 1m0s
[10:35:54.152] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:66:0)
[10:35:54.152] Timer tick, asking Buildbucket for the build status
[10:35:54.581] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:54.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:67:0) after 1m0s
[10:36:54.593] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:67:0)
[10:36:54.593] Timer tick, asking Buildbucket for the build status
[10:36:54.712] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:54.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:68:0) after 1m0s
[10:37:54.733] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:68:0)
[10:37:54.733] Timer tick, asking Buildbucket for the build status
[10:37:54.843] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:54.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:69:0) after 1m0s
[10:38:54.911] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:69:0)
[10:38:54.911] Timer tick, asking Buildbucket for the build status
[10:38:54.988] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:54.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:70:0) after 1m0s
[10:39:55.061] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:70:0)
[10:39:55.061] Timer tick, asking Buildbucket for the build status
[10:39:55.390] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:55.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:71:0) after 1m0s
[10:40:55.401] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:71:0)
[10:40:55.401] Timer tick, asking Buildbucket for the build status
[10:40:55.502] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:55.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:72:0) after 1m0s
[10:41:55.531] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:72:0)
[10:41:55.531] Timer tick, asking Buildbucket for the build status
[10:41:55.723] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:55.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:73:0) after 1m0s
[10:42:55.741] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:73:0)
[10:42:55.741] Timer tick, asking Buildbucket for the build status
[10:42:55.819] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:55.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:74:0) after 1m0s
[10:43:55.863] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:74:0)
[10:43:55.863] Timer tick, asking Buildbucket for the build status
[10:43:56.013] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:56.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:75:0) after 1m0s
[10:44:56.092] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:75:0)
[10:44:56.093] Timer tick, asking Buildbucket for the build status
[10:44:56.173] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:56.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:76:0) after 1m0s
[10:45:56.293] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:76:0)
[10:45:56.293] Timer tick, asking Buildbucket for the build status
[10:45:56.385] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:56.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:77:0) after 1m0s
[10:46:56.498] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:77:0)
[10:46:56.499] Timer tick, asking Buildbucket for the build status
[10:46:56.588] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:56.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:78:0) after 1m0s
[10:47:56.705] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:78:0)
[10:47:56.706] Timer tick, asking Buildbucket for the build status
[10:47:56.783] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:56.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:79:0) after 1m0s
[10:48:57.190] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:79:0)
[10:48:57.190] Timer tick, asking Buildbucket for the build status
[10:48:57.630] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:57.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:80:0) after 1m0s
[10:49:57.698] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:80:0)
[10:49:57.698] Timer tick, asking Buildbucket for the build status
[10:49:57.772] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:57.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:81:0) after 1m0s
[10:50:57.840] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:81:0)
[10:50:57.840] Timer tick, asking Buildbucket for the build status
[10:50:58.033] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:58.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:82:0) after 1m0s
[10:51:58.108] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:82:0)
[10:51:58.108] Timer tick, asking Buildbucket for the build status
[10:51:58.240] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:58.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:83:0) after 1m0s
[10:52:58.249] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:83:0)
[10:52:58.249] Timer tick, asking Buildbucket for the build status
[10:52:58.326] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:58.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:84:0) after 1m0s
[10:53:58.468] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:84:0)
[10:53:58.468] Timer tick, asking Buildbucket for the build status
[10:53:58.565] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:58.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:85:0) after 1m0s
[10:54:58.608] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:85:0)
[10:54:58.608] Timer tick, asking Buildbucket for the build status
[10:54:58.681] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:58.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:86:0) after 1m0s
[10:55:58.733] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:86:0)
[10:55:58.733] Timer tick, asking Buildbucket for the build status
[10:55:58.807] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:58.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:87:0) after 1m0s
[10:56:58.833] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:87:0)
[10:56:58.833] Timer tick, asking Buildbucket for the build status
[10:56:58.986] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:58.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:88:0) after 1m0s
[10:57:59.008] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:88:0)
[10:57:59.008] Timer tick, asking Buildbucket for the build status
[10:57:59.077] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:59.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:89:0) after 1m0s
[10:58:59.288] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:89:0)
[10:58:59.288] Timer tick, asking Buildbucket for the build status
[10:58:59.360] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:59.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:90:0) after 1m0s
[10:59:59.453] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:90:0)
[10:59:59.453] Timer tick, asking Buildbucket for the build status
[10:59:59.525] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:59.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:91:0) after 1m0s
[11:00:59.587] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:91:0)
[11:00:59.587] Timer tick, asking Buildbucket for the build status
[11:00:59.720] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:59.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:92:0) after 1m0s
[11:01:59.797] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:92:0)
[11:01:59.797] Timer tick, asking Buildbucket for the build status
[11:01:59.893] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:59.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:93:0) after 1m0s
[11:02:59.917] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:93:0)
[11:02:59.917] Timer tick, asking Buildbucket for the build status
[11:02:59.989] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:59.989] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:94:0) after 1m0s
[11:04:00.026] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:94:0)
[11:04:00.026] Timer tick, asking Buildbucket for the build status
[11:04:00.546] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:00.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:95:0) after 1m0s
[11:05:01.705] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:95:0)
[11:05:01.705] Timer tick, asking Buildbucket for the build status
[11:05:01.804] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:01.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:96:0) after 1m0s
[11:06:01.881] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:96:0)
[11:06:01.881] Timer tick, asking Buildbucket for the build status
[11:06:02.111] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:02.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:97:0) after 1m0s
[11:07:02.142] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:97:0)
[11:07:02.142] Timer tick, asking Buildbucket for the build status
[11:07:02.232] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:02.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:98:0) after 1m0s
[11:08:02.291] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:98:0)
[11:08:02.292] Timer tick, asking Buildbucket for the build status
[11:08:02.379] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:02.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:99:0) after 1m0s
[11:09:02.466] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:99:0)
[11:09:02.466] Timer tick, asking Buildbucket for the build status
[11:09:02.543] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:02.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:100:0) after 1m0s
[11:10:02.646] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:100:0)
[11:10:02.646] Timer tick, asking Buildbucket for the build status
[11:10:02.907] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:02.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:101:0) after 1m0s
[11:11:02.907] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:101:0)
[11:11:02.907] Timer tick, asking Buildbucket for the build status
[11:11:02.975] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:02.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:102:0) after 1m0s
[11:12:02.996] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:102:0)
[11:12:02.996] Timer tick, asking Buildbucket for the build status
[11:12:03.237] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:03.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:103:0) after 1m0s
[11:13:03.262] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:103:0)
[11:13:03.262] Timer tick, asking Buildbucket for the build status
[11:13:03.339] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:03.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:104:0) after 1m0s
[11:14:03.360] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:104:0)
[11:14:03.360] Timer tick, asking Buildbucket for the build status
[11:14:03.455] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:105:0) after 1m0s
[11:15:03.540] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:105:0)
[11:15:03.540] Timer tick, asking Buildbucket for the build status
[11:15:03.654] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:03.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:106:0) after 1m0s
[11:16:03.694] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:106:0)
[11:16:03.694] Timer tick, asking Buildbucket for the build status
[11:16:03.788] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:03.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:107:0) after 1m0s
[11:17:03.875] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:107:0)
[11:17:03.875] Timer tick, asking Buildbucket for the build status
[11:17:03.977] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:03.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:108:0) after 1m0s
[11:18:04.030] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:108:0)
[11:18:04.030] Timer tick, asking Buildbucket for the build status
[11:18:04.307] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:109:0) after 1m0s
[11:19:04.425] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:109:0)
[11:19:04.425] Timer tick, asking Buildbucket for the build status
[11:19:04.725] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:04.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:110:0) after 1m0s
[11:20:04.781] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:110:0)
[11:20:04.781] Timer tick, asking Buildbucket for the build status
[11:20:04.857] Build 8854878213765989408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:04.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9020308276069736896:111:0) after 1m0s
[11:20:40.775] Received PubSub notification, asking Buildbucket for the build status
[11:20:40.870] Build 8854878213765989408: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:20:40.870] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613733639892247",
"created_by": "project:nacl",
"created_ts": "1613727094834030",
"id": "8854878213765989408",
"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\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"title\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}}",
"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\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"title\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"luci-flex-ci-xenial-5-hc1k\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl\", \"buildername\": \"linux_64-newlib-arm_qemu-pnacl-opt\", \"buildnumber\": 175, \"got_revision\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_128648638a52124010bb106fd411e34109051b8aec58e7e96a1023cfb5053cb5_v2\", \"builder_149fed94fc0315f63a3ac899b53d3cfedce2ab533d8d891f9abe32610aa3106a_v2\", \"builder_5a5bb86f59b56b196ab2fcd890c7333739724a7208c96ec90a4b21c46bf89f1e_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_96b937d0444ca0237e7399236bdd6728df75ff624b2c87b99974683f32fc3980_v2\", \"builder_c4d21eced6de83d49b751ba6a9a51b24a2637effdae03eb3d3659643edf5411d_v2\", \"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"builder_faaedc22e3ac6fec098652e7f0fd10d4f879f8f9ad55ea8ef979585009ee36da_v2\", \"git\", \"gocache\", \"goma_v2\", \"gopath\", \"vpython\", \"win_toolchain\"], \"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-5-hc1k\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613727748616867",
"status": "COMPLETED",
"status_changed_ts": "1613733640526208",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/175",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4ad2ff292145e8613ec371302875f0eaf8b4fa14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020308276069736896",
"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/8854878213765989408/+/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:51d272ee1e641c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613733640537516",
"url": "https://ci.chromium.org/b/8854878213765989408",
"utcnow_ts": "1613733640864002"
}
}
[11:20:40.870] Invocation finished in 1h49m7.616965763s with status SUCCEEDED