[19:00:03.062] New invocation is queued and will start shortly
[19:00:04.275] Starting the invocation (attempt 1)
[19:00:04.312] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:00:04.313] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:00:04.313] Buildbucket request:
{
"bucket": "luci.nacl.ci",
"client_operation_id": "9021178479023338112",
"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\":\"4de55da44c4b511989359381e8b7a88a01fe0634\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4de55da44c4b511989359381e8b7a88a01fe0634\",\"title\":\"4de55da44c4b511989359381e8b7a88a01fe0634\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/4de55da44c4b511989359381e8b7a88a01fe0634\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"4de55da44c4b511989359381e8b7a88a01fe0634\"}}",
"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:9021178479023338112",
"scheduler_job_id:nacl/linux_64-newlib-arm_qemu-pnacl-opt",
"user_agent:luci-scheduler",
"buildset:commit/git/4de55da44c4b511989359381e8b7a88a01fe0634",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4de55da44c4b511989359381e8b7a88a01fe0634",
"gitiles_ref:refs/heads/master"
]
}
[19:00:06.575] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1612897204528867",
"id": "8855748416822733600",
"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\": \"4de55da44c4b511989359381e8b7a88a01fe0634\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4de55da44c4b511989359381e8b7a88a01fe0634\", \"title\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4de55da44c4b511989359381e8b7a88a01fe0634\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612897206079612",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/171",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/4de55da44c4b511989359381e8b7a88a01fe0634",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4de55da44c4b511989359381e8b7a88a01fe0634",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021178479023338112",
"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/8855748416822733600/+/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": "1612897206079760",
"url": "https://ci.chromium.org/b/8855748416822733600",
"utcnow_ts": "1612897206548833"
}
}
[19:00:06.575] Task URL: https://ci.chromium.org/b/8855748416822733600
[19:00:06.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:2:0) after 1m0s
[19:00:47.532] Received PubSub notification, asking Buildbucket for the build status
[19:00:47.646] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:06.588] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:2:0)
[19:01:06.588] Timer tick, asking Buildbucket for the build status
[19:01:07.013] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:07.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:4:0) after 1m0s
[19:02:07.075] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:4:0)
[19:02:07.075] Timer tick, asking Buildbucket for the build status
[19:02:07.238] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:07.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:5:0) after 1m0s
[19:03:07.304] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:5:0)
[19:03:07.304] Timer tick, asking Buildbucket for the build status
[19:03:07.457] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:07.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:6:0) after 1m0s
[19:04:07.519] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:6:0)
[19:04:07.519] Timer tick, asking Buildbucket for the build status
[19:04:09.499] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:09.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:7:0) after 1m0s
[19:05:09.598] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:7:0)
[19:05:09.599] Timer tick, asking Buildbucket for the build status
[19:05:09.730] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:09.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:8:0) after 1m0s
[19:06:09.806] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:8:0)
[19:06:09.806] Timer tick, asking Buildbucket for the build status
[19:06:11.388] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:11.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:9:0) after 1m0s
[19:07:11.533] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:9:0)
[19:07:11.533] Timer tick, asking Buildbucket for the build status
[19:07:11.680] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:11.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:10:0) after 1m0s
[19:08:11.695] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:10:0)
[19:08:11.695] Timer tick, asking Buildbucket for the build status
[19:08:12.729] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:12.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:11:0) after 1m0s
[19:09:12.804] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:11:0)
[19:09:12.804] Timer tick, asking Buildbucket for the build status
[19:09:13.155] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:13.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:12:0) after 1m0s
[19:10:13.286] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:12:0)
[19:10:13.286] Timer tick, asking Buildbucket for the build status
[19:10:14.009] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:14.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:13:0) after 1m0s
[19:11:14.021] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:13:0)
[19:11:14.021] Timer tick, asking Buildbucket for the build status
[19:11:14.232] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:14.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:14:0) after 1m0s
[19:12:14.306] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:14:0)
[19:12:14.306] Timer tick, asking Buildbucket for the build status
[19:12:14.403] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:14.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:15:0) after 1m0s
[19:13:14.466] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:15:0)
[19:13:14.466] Timer tick, asking Buildbucket for the build status
[19:13:14.578] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:14.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:16:0) after 1m0s
[19:14:14.677] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:16:0)
[19:14:14.677] Timer tick, asking Buildbucket for the build status
[19:14:14.888] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:14.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:17:0) after 1m0s
[19:15:14.925] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:17:0)
[19:15:14.925] Timer tick, asking Buildbucket for the build status
[19:15:15.367] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:15.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:18:0) after 1m0s
[19:16:15.608] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:18:0)
[19:16:15.608] Timer tick, asking Buildbucket for the build status
[19:16:16.011] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:16.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:19:0) after 1m0s
[19:17:16.169] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:19:0)
[19:17:16.169] Timer tick, asking Buildbucket for the build status
[19:17:16.615] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:16.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:20:0) after 1m0s
[19:18:16.716] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:20:0)
[19:18:16.716] Timer tick, asking Buildbucket for the build status
[19:18:16.835] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:16.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:21:0) after 1m0s
[19:19:17.415] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:21:0)
[19:19:17.415] Timer tick, asking Buildbucket for the build status
[19:19:20.169] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:20.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:22:0) after 1m0s
[19:20:20.208] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:22:0)
[19:20:20.208] Timer tick, asking Buildbucket for the build status
[19:20:20.472] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:20.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:23:0) after 1m0s
[19:21:20.553] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:23:0)
[19:21:20.553] Timer tick, asking Buildbucket for the build status
[19:21:20.762] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:24:0) after 1m0s
[19:22:20.797] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:24:0)
[19:22:20.797] Timer tick, asking Buildbucket for the build status
[19:22:20.947] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:20.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:25:0) after 1m0s
[19:23:20.927] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:25:0)
[19:23:20.927] Timer tick, asking Buildbucket for the build status
[19:23:21.151] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:21.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:26:0) after 1m0s
[19:24:21.144] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:26:0)
[19:24:21.144] Timer tick, asking Buildbucket for the build status
[19:24:21.298] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:21.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:27:0) after 1m0s
[19:25:21.344] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:27:0)
[19:25:21.344] Timer tick, asking Buildbucket for the build status
[19:25:21.519] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:21.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:28:0) after 1m0s
[19:26:21.583] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:28:0)
[19:26:21.583] Timer tick, asking Buildbucket for the build status
[19:26:21.937] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:21.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:29:0) after 1m0s
[19:27:21.989] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:29:0)
[19:27:21.989] Timer tick, asking Buildbucket for the build status
[19:27:22.356] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:22.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:30:0) after 1m0s
[19:28:22.384] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:30:0)
[19:28:22.384] Timer tick, asking Buildbucket for the build status
[19:28:22.625] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:22.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:31:0) after 1m0s
[19:29:22.668] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:31:0)
[19:29:22.668] Timer tick, asking Buildbucket for the build status
[19:29:23.965] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:23.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:32:0) after 1m0s
[19:30:24.011] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:32:0)
[19:30:24.011] Timer tick, asking Buildbucket for the build status
[19:30:24.189] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:24.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:33:0) after 1m0s
[19:31:24.216] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:33:0)
[19:31:24.216] Timer tick, asking Buildbucket for the build status
[19:31:24.429] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:24.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:34:0) after 1m0s
[19:32:24.526] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:34:0)
[19:32:24.526] Timer tick, asking Buildbucket for the build status
[19:32:24.668] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:24.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:35:0) after 1m0s
[19:33:24.758] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:35:0)
[19:33:24.758] Timer tick, asking Buildbucket for the build status
[19:33:25.158] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:25.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:36:0) after 1m0s
[19:34:25.212] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:36:0)
[19:34:25.212] Timer tick, asking Buildbucket for the build status
[19:34:27.343] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:27.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:37:0) after 1m0s
[19:35:27.544] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:37:0)
[19:35:27.544] Timer tick, asking Buildbucket for the build status
[19:35:28.126] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:28.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:38:0) after 1m0s
[19:36:28.932] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:38:0)
[19:36:28.932] Timer tick, asking Buildbucket for the build status
[19:36:29.003] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:29.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:39:0) after 1m0s
[19:37:29.256] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:39:0)
[19:37:29.256] Timer tick, asking Buildbucket for the build status
[19:37:29.518] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:29.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:40:0) after 1m0s
[19:38:29.635] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:40:0)
[19:38:29.635] Timer tick, asking Buildbucket for the build status
[19:38:30.494] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:30.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:41:0) after 1m0s
[19:39:30.621] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:41:0)
[19:39:30.621] Timer tick, asking Buildbucket for the build status
[19:39:30.817] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:30.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:42:0) after 1m0s
[19:40:30.885] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:42:0)
[19:40:30.885] Timer tick, asking Buildbucket for the build status
[19:40:31.098] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:31.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:43:0) after 1m0s
[19:41:31.268] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:43:0)
[19:41:31.268] Timer tick, asking Buildbucket for the build status
[19:41:31.450] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:31.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:44:0) after 1m0s
[19:42:31.483] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:44:0)
[19:42:31.483] Timer tick, asking Buildbucket for the build status
[19:42:31.914] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:31.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:45:0) after 1m0s
[19:43:31.955] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:45:0)
[19:43:31.955] Timer tick, asking Buildbucket for the build status
[19:43:32.089] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:32.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:46:0) after 1m0s
[19:44:32.258] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:46:0)
[19:44:32.258] Timer tick, asking Buildbucket for the build status
[19:44:33.475] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:33.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:47:0) after 1m0s
[19:45:33.502] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:47:0)
[19:45:33.502] Timer tick, asking Buildbucket for the build status
[19:45:33.663] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:33.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:48:0) after 1m0s
[19:46:33.686] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:48:0)
[19:46:33.686] Timer tick, asking Buildbucket for the build status
[19:46:33.780] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:33.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:49:0) after 1m0s
[19:47:33.799] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:49:0)
[19:47:33.799] Timer tick, asking Buildbucket for the build status
[19:47:35.780] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:35.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:50:0) after 1m0s
[19:48:35.889] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:50:0)
[19:48:35.889] Timer tick, asking Buildbucket for the build status
[19:48:36.004] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:36.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:51:0) after 1m0s
[19:49:36.028] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:51:0)
[19:49:36.028] Timer tick, asking Buildbucket for the build status
[19:49:36.084] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:36.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:52:0) after 1m0s
[19:50:36.140] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:52:0)
[19:50:36.140] Timer tick, asking Buildbucket for the build status
[19:50:40.415] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:40.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:53:0) after 1m0s
[19:51:40.508] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:53:0)
[19:51:40.508] Timer tick, asking Buildbucket for the build status
[19:51:40.774] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:40.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:54:0) after 1m0s
[19:52:40.771] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:54:0)
[19:52:40.771] Timer tick, asking Buildbucket for the build status
[19:52:41.392] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:41.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:55:0) after 1m0s
[19:53:41.494] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:55:0)
[19:53:41.494] Timer tick, asking Buildbucket for the build status
[19:53:42.551] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:42.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:56:0) after 1m0s
[19:54:42.637] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:56:0)
[19:54:42.637] Timer tick, asking Buildbucket for the build status
[19:54:42.762] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:42.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:57:0) after 1m0s
[19:55:42.783] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:57:0)
[19:55:42.783] Timer tick, asking Buildbucket for the build status
[19:55:42.961] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:42.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:58:0) after 1m0s
[19:56:43.000] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:58:0)
[19:56:43.000] Timer tick, asking Buildbucket for the build status
[19:56:43.216] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:43.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:59:0) after 1m0s
[19:57:43.247] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:59:0)
[19:57:43.247] Timer tick, asking Buildbucket for the build status
[19:57:43.328] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:43.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:60:0) after 1m0s
[19:58:43.422] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:60:0)
[19:58:43.422] Timer tick, asking Buildbucket for the build status
[19:58:43.840] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:43.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:61:0) after 1m0s
[19:59:43.881] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:61:0)
[19:59:43.882] Timer tick, asking Buildbucket for the build status
[19:59:44.037] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:44.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:62:0) after 1m0s
[20:00:44.075] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:62:0)
[20:00:44.075] Timer tick, asking Buildbucket for the build status
[20:00:44.252] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:44.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:63:0) after 1m0s
[20:01:44.255] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:63:0)
[20:01:44.255] Timer tick, asking Buildbucket for the build status
[20:01:44.543] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:44.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:64:0) after 1m0s
[20:02:44.631] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:64:0)
[20:02:44.631] Timer tick, asking Buildbucket for the build status
[20:02:45.479] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:45.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:65:0) after 1m0s
[20:03:45.540] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:65:0)
[20:03:45.540] Timer tick, asking Buildbucket for the build status
[20:03:46.785] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:46.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:66:0) after 1m0s
[20:04:46.763] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:66:0)
[20:04:46.763] Timer tick, asking Buildbucket for the build status
[20:04:47.054] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:47.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:67:0) after 1m0s
[20:05:47.076] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:67:0)
[20:05:47.076] Timer tick, asking Buildbucket for the build status
[20:05:47.434] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:47.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:68:0) after 1m0s
[20:06:47.472] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:68:0)
[20:06:47.472] Timer tick, asking Buildbucket for the build status
[20:06:47.778] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:47.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:69:0) after 1m0s
[20:07:47.803] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:69:0)
[20:07:47.803] Timer tick, asking Buildbucket for the build status
[20:07:48.149] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:48.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:70:0) after 1m0s
[20:08:48.166] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:70:0)
[20:08:48.166] Timer tick, asking Buildbucket for the build status
[20:08:48.267] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:48.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:71:0) after 1m0s
[20:09:48.406] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:71:0)
[20:09:48.406] Timer tick, asking Buildbucket for the build status
[20:09:49.739] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:49.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:72:0) after 1m0s
[20:10:49.814] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:72:0)
[20:10:49.814] Timer tick, asking Buildbucket for the build status
[20:10:49.976] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:49.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:73:0) after 1m0s
[20:11:49.953] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:73:0)
[20:11:49.953] Timer tick, asking Buildbucket for the build status
[20:11:50.231] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:50.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:74:0) after 1m0s
[20:12:50.238] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:74:0)
[20:12:50.238] Timer tick, asking Buildbucket for the build status
[20:12:50.656] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:50.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:75:0) after 1m0s
[20:13:50.733] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:75:0)
[20:13:50.733] Timer tick, asking Buildbucket for the build status
[20:13:50.977] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:50.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:76:0) after 1m0s
[20:14:51.001] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:76:0)
[20:14:51.001] Timer tick, asking Buildbucket for the build status
[20:14:51.100] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:51.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:77:0) after 1m0s
[20:15:51.109] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:77:0)
[20:15:51.109] Timer tick, asking Buildbucket for the build status
[20:15:51.220] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:51.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:78:0) after 1m0s
[20:16:51.250] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:78:0)
[20:16:51.250] Timer tick, asking Buildbucket for the build status
[20:16:51.438] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:51.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:79:0) after 1m0s
[20:17:51.537] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:79:0)
[20:17:51.537] Timer tick, asking Buildbucket for the build status
[20:17:51.639] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:51.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:80:0) after 1m0s
[20:18:51.643] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:80:0)
[20:18:51.643] Timer tick, asking Buildbucket for the build status
[20:18:51.914] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:51.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:81:0) after 1m0s
[20:19:51.988] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:81:0)
[20:19:51.988] Timer tick, asking Buildbucket for the build status
[20:19:52.055] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:52.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:82:0) after 1m0s
[20:20:52.211] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:82:0)
[20:20:52.211] Timer tick, asking Buildbucket for the build status
[20:20:52.603] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:52.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:83:0) after 1m0s
[20:21:52.627] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:83:0)
[20:21:52.627] Timer tick, asking Buildbucket for the build status
[20:21:52.709] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:52.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:84:0) after 1m0s
[20:22:52.821] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:84:0)
[20:22:52.821] Timer tick, asking Buildbucket for the build status
[20:22:56.116] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:56.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:85:0) after 1m0s
[20:23:56.144] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:85:0)
[20:23:56.144] Timer tick, asking Buildbucket for the build status
[20:23:56.406] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:56.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:86:0) after 1m0s
[20:24:56.442] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:86:0)
[20:24:56.442] Timer tick, asking Buildbucket for the build status
[20:24:56.815] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:56.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:87:0) after 1m0s
[20:25:56.840] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:87:0)
[20:25:56.840] Timer tick, asking Buildbucket for the build status
[20:25:57.898] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:57.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:88:0) after 1m0s
[20:26:57.967] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:88:0)
[20:26:57.967] Timer tick, asking Buildbucket for the build status
[20:26:58.427] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:58.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:89:0) after 1m0s
[20:27:58.456] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:89:0)
[20:27:58.456] Timer tick, asking Buildbucket for the build status
[20:27:58.585] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:58.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:90:0) after 1m0s
[20:28:58.582] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:90:0)
[20:28:58.582] Timer tick, asking Buildbucket for the build status
[20:28:58.710] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:58.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:91:0) after 1m0s
[20:29:58.737] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:91:0)
[20:29:58.737] Timer tick, asking Buildbucket for the build status
[20:29:58.815] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:58.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:92:0) after 1m0s
[20:30:58.873] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:92:0)
[20:30:58.874] Timer tick, asking Buildbucket for the build status
[20:30:59.729] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:59.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:93:0) after 1m0s
[20:31:59.771] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:93:0)
[20:31:59.771] Timer tick, asking Buildbucket for the build status
[20:32:00.200] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:00.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:94:0) after 1m0s
[20:33:00.229] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:94:0)
[20:33:00.229] Timer tick, asking Buildbucket for the build status
[20:33:00.308] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:00.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:95:0) after 1m0s
[20:34:00.317] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:95:0)
[20:34:00.317] Timer tick, asking Buildbucket for the build status
[20:34:00.398] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:00.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:96:0) after 1m0s
[20:35:00.398] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:96:0)
[20:35:00.398] Timer tick, asking Buildbucket for the build status
[20:35:00.701] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:00.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:97:0) after 1m0s
[20:36:00.915] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:97:0)
[20:36:00.915] Timer tick, asking Buildbucket for the build status
[20:36:01.056] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:01.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:98:0) after 1m0s
[20:37:01.256] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:98:0)
[20:37:01.256] Timer tick, asking Buildbucket for the build status
[20:37:01.687] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:01.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:99:0) after 1m0s
[20:38:01.917] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:99:0)
[20:38:01.917] Timer tick, asking Buildbucket for the build status
[20:38:02.538] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:02.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:100:0) after 1m0s
[20:39:02.638] Handling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:100:0)
[20:39:02.638] Timer tick, asking Buildbucket for the build status
[20:39:02.708] Build 8855748416822733600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:02.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux_64-newlib-arm_qemu-pnacl-opt:9021178479023338112:101:0) after 1m0s
[20:39:57.730] Received PubSub notification, asking Buildbucket for the build status
[20:39:57.822] Build 8855748416822733600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:39:57.822] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.ci",
"canary_preference": "PROD",
"completed_ts": "1612903196797019",
"created_by": "project:nacl",
"created_ts": "1612897204528867",
"id": "8855748416822733600",
"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\": \"4de55da44c4b511989359381e8b7a88a01fe0634\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4de55da44c4b511989359381e8b7a88a01fe0634\", \"title\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4de55da44c4b511989359381e8b7a88a01fe0634\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\"}}",
"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\": \"4de55da44c4b511989359381e8b7a88a01fe0634\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@4de55da44c4b511989359381e8b7a88a01fe0634\", \"title\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/4de55da44c4b511989359381e8b7a88a01fe0634\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"luci-flex-ci-xenial-13-n7t7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl\", \"buildername\": \"linux_64-newlib-arm_qemu-pnacl-opt\", \"buildnumber\": 171, \"got_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_08d95548a5b2763894be45da75746e0a26ecb57e0e5972c9d07f9ce07d542665_v2\", \"builder_30037600afb0fc8938a6d537e3c45a46491e7159df17e0dae8492f75116b87ca_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"git\", \"goma_v2\", \"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-13-n7t7\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612897244811303",
"status": "COMPLETED",
"status_changed_ts": "1612903197487408",
"tags": [
"build_address:luci.nacl.ci/linux_64-newlib-arm_qemu-pnacl-opt/171",
"builder:linux_64-newlib-arm_qemu-pnacl-opt",
"buildset:commit/git/4de55da44c4b511989359381e8b7a88a01fe0634",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/4de55da44c4b511989359381e8b7a88a01fe0634",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021178479023338112",
"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/8855748416822733600/+/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:51a0fbe369a1d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1612903197500169",
"url": "https://ci.chromium.org/b/8855748416822733600",
"utcnow_ts": "1612903197812368"
}
}
[20:39:57.822] Invocation finished in 1h39m54.776933888s with status SUCCEEDED