[23:11:32.775] New invocation is queued and will start shortly
[23:11:34.141] Starting the invocation (attempt 1)
[23:11:34.194] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:11:34.196] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:11:34.196] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9089563365943910224",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64",
"scheduler_invocation_id:9089563365943910224",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"user_agent:luci-scheduler",
"buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
"gitiles_ref:refs/heads/master"
]
}
[23:11:35.476] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547680294258820",
"id": "8924133303726075328",
"lease_expiration_ts": "1547831494989702",
"lease_key": "237908243",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
"project": "nacl",
"result_details_json": "null",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1547680295321065",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7018",
"builder:linux-pnacl-x86_64",
"buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089563365943910224",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_dimension:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.nacl.toolchain",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7018",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8924133303726075328",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:linux-pnacl-x86_64",
"swarming_tag:buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
"swarming_tag:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924133303726075328/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.nacl.toolchain",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9089563365943910224",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:e1d5da8663c0550abd5affb209a08bfdb819ce3f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4271c0af684c1310",
"user_agent:luci-scheduler"
],
"updated_ts": "1547680295326812",
"url": "https://ci.chromium.org/b/8924133303726075328",
"utcnow_ts": "1547680295465882"
}
}
[23:11:35.476] Task URL: https://ci.chromium.org/b/8924133303726075328
[23:11:35.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:2:0) after 1m0s
[23:12:11.337] Received PubSub notification, asking Buildbucket for the build status
[23:12:11.373] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:2:0)
[23:12:35.497] Timer tick, asking Buildbucket for the build status
[23:12:35.616] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:4:0) after 1m0s
[23:13:35.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:4:0)
[23:13:35.640] Timer tick, asking Buildbucket for the build status
[23:13:35.691] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:35.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:5:0) after 1m0s
[23:14:35.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:5:0)
[23:14:35.707] Timer tick, asking Buildbucket for the build status
[23:14:35.774] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:35.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:6:0) after 1m0s
[23:15:35.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:6:0)
[23:15:35.798] Timer tick, asking Buildbucket for the build status
[23:15:35.945] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:35.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:7:0) after 1m0s
[23:16:35.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:7:0)
[23:16:35.987] Timer tick, asking Buildbucket for the build status
[23:16:36.045] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:8:0) after 1m0s
[23:17:36.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:8:0)
[23:17:36.067] Timer tick, asking Buildbucket for the build status
[23:17:36.149] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:9:0) after 1m0s
[23:18:36.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:9:0)
[23:18:36.169] Timer tick, asking Buildbucket for the build status
[23:18:36.244] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:10:0) after 1m0s
[23:19:36.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:10:0)
[23:19:36.260] Timer tick, asking Buildbucket for the build status
[23:19:36.352] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:11:0) after 1m0s
[23:20:36.399] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:11:0)
[23:20:36.399] Timer tick, asking Buildbucket for the build status
[23:20:36.773] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:12:0) after 1m0s
[23:21:36.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:12:0)
[23:21:36.789] Timer tick, asking Buildbucket for the build status
[23:21:36.850] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:36.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:13:0) after 1m0s
[23:22:36.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:13:0)
[23:22:36.918] Timer tick, asking Buildbucket for the build status
[23:22:36.987] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:36.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:14:0) after 1m0s
[23:23:37.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:14:0)
[23:23:37.005] Timer tick, asking Buildbucket for the build status
[23:23:37.089] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:37.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:15:0) after 1m0s
[23:24:37.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:15:0)
[23:24:37.105] Timer tick, asking Buildbucket for the build status
[23:24:37.194] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:37.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:16:0) after 1m0s
[23:25:37.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:16:0)
[23:25:37.210] Timer tick, asking Buildbucket for the build status
[23:25:37.275] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:37.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:17:0) after 1m0s
[23:26:37.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:17:0)
[23:26:37.296] Timer tick, asking Buildbucket for the build status
[23:26:37.413] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:37.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:18:0) after 1m0s
[23:27:37.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:18:0)
[23:27:37.433] Timer tick, asking Buildbucket for the build status
[23:27:37.497] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:19:0) after 1m0s
[23:28:37.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:19:0)
[23:28:37.514] Timer tick, asking Buildbucket for the build status
[23:28:37.592] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:37.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:20:0) after 1m0s
[23:29:37.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:20:0)
[23:29:37.618] Timer tick, asking Buildbucket for the build status
[23:29:37.711] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:37.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:21:0) after 1m0s
[23:30:37.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:21:0)
[23:30:37.770] Timer tick, asking Buildbucket for the build status
[23:30:37.942] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:22:0) after 1m0s
[23:31:37.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:22:0)
[23:31:37.972] Timer tick, asking Buildbucket for the build status
[23:31:38.035] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:23:0) after 1m0s
[23:32:38.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:23:0)
[23:32:38.053] Timer tick, asking Buildbucket for the build status
[23:32:38.141] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:24:0) after 1m0s
[23:33:38.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:24:0)
[23:33:38.196] Timer tick, asking Buildbucket for the build status
[23:33:38.344] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:25:0) after 1m0s
[23:34:38.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:25:0)
[23:34:38.361] Timer tick, asking Buildbucket for the build status
[23:34:38.487] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:38.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:26:0) after 1m0s
[23:35:38.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:26:0)
[23:35:38.610] Timer tick, asking Buildbucket for the build status
[23:35:38.683] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:38.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:27:0) after 1m0s
[23:36:41.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:27:0)
[23:36:41.133] Timer tick, asking Buildbucket for the build status
[23:36:41.190] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:28:0) after 1m0s
[23:37:41.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:28:0)
[23:37:41.215] Timer tick, asking Buildbucket for the build status
[23:37:41.285] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:41.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:29:0) after 1m0s
[23:38:41.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:29:0)
[23:38:41.305] Timer tick, asking Buildbucket for the build status
[23:38:41.387] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:41.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:30:0) after 1m0s
[23:39:41.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:30:0)
[23:39:41.433] Timer tick, asking Buildbucket for the build status
[23:39:41.490] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:41.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:31:0) after 1m0s
[23:40:41.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:31:0)
[23:40:41.608] Timer tick, asking Buildbucket for the build status
[23:40:41.695] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:41.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:32:0) after 1m0s
[23:41:41.723] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:32:0)
[23:41:41.723] Timer tick, asking Buildbucket for the build status
[23:41:41.787] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:33:0) after 1m0s
[23:42:42.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:33:0)
[23:42:42.193] Timer tick, asking Buildbucket for the build status
[23:42:42.258] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:42.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:34:0) after 1m0s
[23:43:42.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:34:0)
[23:43:42.311] Timer tick, asking Buildbucket for the build status
[23:43:42.374] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:42.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:35:0) after 1m0s
[23:44:42.421] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:35:0)
[23:44:42.421] Timer tick, asking Buildbucket for the build status
[23:44:42.493] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:42.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:36:0) after 1m0s
[23:45:42.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:36:0)
[23:45:42.616] Timer tick, asking Buildbucket for the build status
[23:45:42.652] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:42.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:37:0) after 1m0s
[23:46:42.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:37:0)
[23:46:42.676] Timer tick, asking Buildbucket for the build status
[23:46:42.716] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:42.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:38:0) after 1m0s
[23:47:42.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:38:0)
[23:47:42.740] Timer tick, asking Buildbucket for the build status
[23:47:42.773] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:42.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:39:0) after 1m0s
[23:48:42.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:39:0)
[23:48:42.789] Timer tick, asking Buildbucket for the build status
[23:48:42.857] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:42.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:40:0) after 1m0s
[23:49:42.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:40:0)
[23:49:42.902] Timer tick, asking Buildbucket for the build status
[23:49:42.958] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:42.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:41:0) after 1m0s
[23:50:42.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:41:0)
[23:50:42.976] Timer tick, asking Buildbucket for the build status
[23:50:43.027] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:43.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:42:0) after 1m0s
[23:51:43.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:42:0)
[23:51:43.059] Timer tick, asking Buildbucket for the build status
[23:51:43.135] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:43.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:43:0) after 1m0s
[23:52:43.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:43:0)
[23:52:43.153] Timer tick, asking Buildbucket for the build status
[23:52:43.193] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:43.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:44:0) after 1m0s
[23:53:43.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:44:0)
[23:53:43.215] Timer tick, asking Buildbucket for the build status
[23:53:43.278] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:43.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:45:0) after 1m0s
[23:54:43.300] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:45:0)
[23:54:43.300] Timer tick, asking Buildbucket for the build status
[23:54:43.379] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:43.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:46:0) after 1m0s
[23:55:43.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:46:0)
[23:55:43.404] Timer tick, asking Buildbucket for the build status
[23:55:43.456] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:43.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:47:0) after 1m0s
[23:56:43.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:47:0)
[23:56:43.488] Timer tick, asking Buildbucket for the build status
[23:56:43.547] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:43.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:48:0) after 1m0s
[23:57:43.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:48:0)
[23:57:43.582] Timer tick, asking Buildbucket for the build status
[23:57:43.636] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:43.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:49:0) after 1m0s
[23:58:43.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:49:0)
[23:58:43.666] Timer tick, asking Buildbucket for the build status
[23:58:43.706] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:43.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:50:0) after 1m0s
[23:59:43.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:50:0)
[23:59:43.725] Timer tick, asking Buildbucket for the build status
[23:59:43.763] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:43.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:51:0) after 1m0s
[00:00:44.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:51:0)
[00:00:44.156] Timer tick, asking Buildbucket for the build status
[00:00:44.377] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:44.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:52:0) after 1m0s
[00:01:44.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:52:0)
[00:01:44.426] Timer tick, asking Buildbucket for the build status
[00:01:44.492] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:44.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:53:0) after 1m0s
[00:02:44.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:53:0)
[00:02:44.539] Timer tick, asking Buildbucket for the build status
[00:02:44.644] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:44.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:54:0) after 1m0s
[00:03:44.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:54:0)
[00:03:44.668] Timer tick, asking Buildbucket for the build status
[00:03:44.733] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:44.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:55:0) after 1m0s
[00:04:44.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:55:0)
[00:04:44.751] Timer tick, asking Buildbucket for the build status
[00:04:44.809] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:44.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:56:0) after 1m0s
[00:05:44.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:56:0)
[00:05:44.830] Timer tick, asking Buildbucket for the build status
[00:05:44.866] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:44.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:57:0) after 1m0s
[00:06:45.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:57:0)
[00:06:45.637] Timer tick, asking Buildbucket for the build status
[00:06:45.705] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:45.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:58:0) after 1m0s
[00:07:45.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:58:0)
[00:07:45.727] Timer tick, asking Buildbucket for the build status
[00:07:45.781] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:45.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:59:0) after 1m0s
[00:08:45.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:59:0)
[00:08:45.900] Timer tick, asking Buildbucket for the build status
[00:08:45.947] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:45.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:60:0) after 1m0s
[00:09:45.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:60:0)
[00:09:45.968] Timer tick, asking Buildbucket for the build status
[00:09:46.020] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:46.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:61:0) after 1m0s
[00:10:46.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:61:0)
[00:10:46.044] Timer tick, asking Buildbucket for the build status
[00:10:46.120] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:62:0) after 1m0s
[00:11:46.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:62:0)
[00:11:46.217] Timer tick, asking Buildbucket for the build status
[00:11:46.339] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:46.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:63:0) after 1m0s
[00:12:46.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:63:0)
[00:12:46.367] Timer tick, asking Buildbucket for the build status
[00:12:46.413] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:46.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:64:0) after 1m0s
[00:13:46.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:64:0)
[00:13:46.438] Timer tick, asking Buildbucket for the build status
[00:13:46.480] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:46.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:65:0) after 1m0s
[00:14:46.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:65:0)
[00:14:46.500] Timer tick, asking Buildbucket for the build status
[00:14:46.562] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:46.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:66:0) after 1m0s
[00:15:46.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:66:0)
[00:15:46.582] Timer tick, asking Buildbucket for the build status
[00:15:46.621] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:46.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:67:0) after 1m0s
[00:16:46.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:67:0)
[00:16:46.640] Timer tick, asking Buildbucket for the build status
[00:16:46.807] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:46.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:68:0) after 1m0s
[00:17:46.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:68:0)
[00:17:46.832] Timer tick, asking Buildbucket for the build status
[00:17:46.979] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:46.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:69:0) after 1m0s
[00:18:47.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:69:0)
[00:18:47.023] Timer tick, asking Buildbucket for the build status
[00:18:47.111] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:47.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:70:0) after 1m0s
[00:19:47.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:70:0)
[00:19:47.164] Timer tick, asking Buildbucket for the build status
[00:19:47.297] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:47.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:71:0) after 1m0s
[00:20:47.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:71:0)
[00:20:47.377] Timer tick, asking Buildbucket for the build status
[00:20:47.421] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:47.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:72:0) after 1m0s
[00:21:47.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:72:0)
[00:21:47.538] Timer tick, asking Buildbucket for the build status
[00:21:47.595] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:47.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:73:0) after 1m0s
[00:22:47.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:73:0)
[00:22:47.622] Timer tick, asking Buildbucket for the build status
[00:22:47.666] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:47.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:74:0) after 1m0s
[00:23:47.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:74:0)
[00:23:47.684] Timer tick, asking Buildbucket for the build status
[00:23:47.764] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:47.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:75:0) after 1m0s
[00:24:47.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:75:0)
[00:24:47.815] Timer tick, asking Buildbucket for the build status
[00:24:48.169] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:76:0) after 1m0s
[00:25:48.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:76:0)
[00:25:48.186] Timer tick, asking Buildbucket for the build status
[00:25:48.275] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:77:0) after 1m0s
[00:26:48.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:77:0)
[00:26:48.294] Timer tick, asking Buildbucket for the build status
[00:26:48.391] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:48.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:78:0) after 1m0s
[00:27:48.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:78:0)
[00:27:48.408] Timer tick, asking Buildbucket for the build status
[00:27:48.736] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:48.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:79:0) after 1m0s
[00:28:48.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:79:0)
[00:28:48.754] Timer tick, asking Buildbucket for the build status
[00:28:48.798] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:48.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:80:0) after 1m0s
[00:29:48.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:80:0)
[00:29:48.815] Timer tick, asking Buildbucket for the build status
[00:29:48.870] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:48.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:81:0) after 1m0s
[00:30:48.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:81:0)
[00:30:48.941] Timer tick, asking Buildbucket for the build status
[00:30:49.170] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:82:0) after 1m0s
[00:31:49.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:82:0)
[00:31:49.194] Timer tick, asking Buildbucket for the build status
[00:31:49.245] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:83:0) after 1m0s
[00:32:49.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:83:0)
[00:32:49.264] Timer tick, asking Buildbucket for the build status
[00:32:49.301] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:49.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:84:0) after 1m0s
[00:33:49.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:84:0)
[00:33:49.320] Timer tick, asking Buildbucket for the build status
[00:33:49.382] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:49.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:85:0) after 1m0s
[00:34:49.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:85:0)
[00:34:49.403] Timer tick, asking Buildbucket for the build status
[00:34:49.454] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:49.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:86:0) after 1m0s
[00:35:49.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:86:0)
[00:35:49.474] Timer tick, asking Buildbucket for the build status
[00:35:49.532] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:49.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:87:0) after 1m0s
[00:36:50.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:87:0)
[00:36:50.276] Timer tick, asking Buildbucket for the build status
[00:36:50.524] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:50.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:88:0) after 1m0s
[00:37:50.545] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:88:0)
[00:37:50.545] Timer tick, asking Buildbucket for the build status
[00:37:50.607] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:50.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:89:0) after 1m0s
[00:38:50.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:89:0)
[00:38:50.817] Timer tick, asking Buildbucket for the build status
[00:38:50.918] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:50.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:90:0) after 1m0s
[00:39:50.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:90:0)
[00:39:50.940] Timer tick, asking Buildbucket for the build status
[00:39:51.005] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:51.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:91:0) after 1m0s
[00:40:51.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:91:0)
[00:40:51.025] Timer tick, asking Buildbucket for the build status
[00:40:51.064] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:92:0) after 1m0s
[00:41:51.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:92:0)
[00:41:51.084] Timer tick, asking Buildbucket for the build status
[00:41:51.131] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:93:0) after 1m0s
[00:42:51.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:93:0)
[00:42:51.156] Timer tick, asking Buildbucket for the build status
[00:42:51.233] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:51.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:94:0) after 1m0s
[00:43:51.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:94:0)
[00:43:51.258] Timer tick, asking Buildbucket for the build status
[00:43:51.342] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:51.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:95:0) after 1m0s
[00:44:51.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:95:0)
[00:44:51.367] Timer tick, asking Buildbucket for the build status
[00:44:51.404] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:51.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:96:0) after 1m0s
[00:45:51.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:96:0)
[00:45:51.429] Timer tick, asking Buildbucket for the build status
[00:45:51.473] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:51.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:97:0) after 1m0s
[00:46:51.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:97:0)
[00:46:51.492] Timer tick, asking Buildbucket for the build status
[00:46:51.580] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:51.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:98:0) after 1m0s
[00:47:51.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:98:0)
[00:47:51.626] Timer tick, asking Buildbucket for the build status
[00:47:51.672] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:51.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:99:0) after 1m0s
[00:48:51.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:99:0)
[00:48:51.705] Timer tick, asking Buildbucket for the build status
[00:48:51.788] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:51.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:100:0) after 1m0s
[00:49:51.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:100:0)
[00:49:51.804] Timer tick, asking Buildbucket for the build status
[00:49:51.907] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:51.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:101:0) after 1m0s
[00:50:52.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:101:0)
[00:50:52.003] Timer tick, asking Buildbucket for the build status
[00:50:52.089] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:52.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:102:0) after 1m0s
[00:51:52.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:102:0)
[00:51:52.107] Timer tick, asking Buildbucket for the build status
[00:51:52.147] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:52.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:103:0) after 1m0s
[00:52:52.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:103:0)
[00:52:52.167] Timer tick, asking Buildbucket for the build status
[00:52:52.266] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:52.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:104:0) after 1m0s
[00:53:52.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:104:0)
[00:53:52.288] Timer tick, asking Buildbucket for the build status
[00:53:52.346] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:52.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:105:0) after 1m0s
[00:54:52.372] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:105:0)
[00:54:52.372] Timer tick, asking Buildbucket for the build status
[00:54:52.413] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:52.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:106:0) after 1m0s
[00:55:52.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:106:0)
[00:55:52.506] Timer tick, asking Buildbucket for the build status
[00:55:52.546] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:52.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:107:0) after 1m0s
[00:56:52.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:107:0)
[00:56:52.566] Timer tick, asking Buildbucket for the build status
[00:56:52.624] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:52.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:108:0) after 1m0s
[00:57:52.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:108:0)
[00:57:52.675] Timer tick, asking Buildbucket for the build status
[00:57:52.749] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:52.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:109:0) after 1m0s
[00:58:52.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:109:0)
[00:58:52.776] Timer tick, asking Buildbucket for the build status
[00:58:52.962] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:52.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:110:0) after 1m0s
[00:59:52.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:110:0)
[00:59:52.997] Timer tick, asking Buildbucket for the build status
[00:59:53.037] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:53.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:111:0) after 1m0s
[01:00:53.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:111:0)
[01:00:53.077] Timer tick, asking Buildbucket for the build status
[01:00:53.161] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:53.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:112:0) after 1m0s
[01:01:53.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:112:0)
[01:01:53.184] Timer tick, asking Buildbucket for the build status
[01:01:53.314] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:53.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:113:0) after 1m0s
[01:02:53.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:113:0)
[01:02:53.338] Timer tick, asking Buildbucket for the build status
[01:02:53.377] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:53.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:114:0) after 1m0s
[01:03:53.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:114:0)
[01:03:53.398] Timer tick, asking Buildbucket for the build status
[01:03:53.473] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:53.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:115:0) after 1m0s
[01:04:53.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:115:0)
[01:04:53.493] Timer tick, asking Buildbucket for the build status
[01:04:53.533] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:53.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:116:0) after 1m0s
[01:05:53.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:116:0)
[01:05:53.563] Timer tick, asking Buildbucket for the build status
[01:05:53.639] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:53.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:117:0) after 1m0s
[01:06:53.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:117:0)
[01:06:53.659] Timer tick, asking Buildbucket for the build status
[01:06:53.716] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:53.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:118:0) after 1m0s
[01:07:53.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:118:0)
[01:07:53.741] Timer tick, asking Buildbucket for the build status
[01:07:54.144] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:54.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:119:0) after 1m0s
[01:08:54.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:119:0)
[01:08:54.172] Timer tick, asking Buildbucket for the build status
[01:08:54.252] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:54.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:120:0) after 1m0s
[01:09:54.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:120:0)
[01:09:54.273] Timer tick, asking Buildbucket for the build status
[01:09:54.636] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:54.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:121:0) after 1m0s
[01:10:54.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:121:0)
[01:10:54.655] Timer tick, asking Buildbucket for the build status
[01:10:54.725] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:54.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:122:0) after 1m0s
[01:11:54.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:122:0)
[01:11:54.749] Timer tick, asking Buildbucket for the build status
[01:11:54.805] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:54.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:123:0) after 1m0s
[01:12:54.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:123:0)
[01:12:54.827] Timer tick, asking Buildbucket for the build status
[01:12:54.943] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:54.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:124:0) after 1m0s
[01:13:54.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:124:0)
[01:13:54.970] Timer tick, asking Buildbucket for the build status
[01:13:55.049] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:55.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:125:0) after 1m0s
[01:14:55.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:125:0)
[01:14:55.069] Timer tick, asking Buildbucket for the build status
[01:14:55.135] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:55.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:126:0) after 1m0s
[01:15:55.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:126:0)
[01:15:55.155] Timer tick, asking Buildbucket for the build status
[01:15:55.195] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:55.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:127:0) after 1m0s
[01:16:55.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:127:0)
[01:16:55.218] Timer tick, asking Buildbucket for the build status
[01:16:55.263] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:55.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:128:0) after 1m0s
[01:17:55.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:128:0)
[01:17:55.284] Timer tick, asking Buildbucket for the build status
[01:17:55.397] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:55.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:129:0) after 1m0s
[01:18:55.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:129:0)
[01:18:55.424] Timer tick, asking Buildbucket for the build status
[01:18:55.509] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:55.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:130:0) after 1m0s
[01:19:55.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:130:0)
[01:19:55.530] Timer tick, asking Buildbucket for the build status
[01:19:55.588] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:131:0) after 1m0s
[01:20:55.611] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:131:0)
[01:20:55.611] Timer tick, asking Buildbucket for the build status
[01:20:55.643] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:132:0) after 1m0s
[01:21:55.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:132:0)
[01:21:55.670] Timer tick, asking Buildbucket for the build status
[01:21:55.750] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:55.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:133:0) after 1m0s
[01:22:55.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:133:0)
[01:22:55.777] Timer tick, asking Buildbucket for the build status
[01:22:55.858] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:55.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:134:0) after 1m0s
[01:23:55.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:134:0)
[01:23:55.879] Timer tick, asking Buildbucket for the build status
[01:23:55.986] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:55.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:135:0) after 1m0s
[01:24:56.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:135:0)
[01:24:56.011] Timer tick, asking Buildbucket for the build status
[01:24:56.073] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:56.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:136:0) after 1m0s
[01:25:56.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:136:0)
[01:25:56.099] Timer tick, asking Buildbucket for the build status
[01:25:56.161] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:56.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:137:0) after 1m0s
[01:26:56.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:137:0)
[01:26:56.203] Timer tick, asking Buildbucket for the build status
[01:26:56.277] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:56.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:138:0) after 1m0s
[01:27:56.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:138:0)
[01:27:56.301] Timer tick, asking Buildbucket for the build status
[01:27:56.526] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:56.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:139:0) after 1m0s
[01:28:56.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:139:0)
[01:28:56.559] Timer tick, asking Buildbucket for the build status
[01:28:56.601] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:56.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:140:0) after 1m0s
[01:29:56.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:140:0)
[01:29:56.773] Timer tick, asking Buildbucket for the build status
[01:29:56.880] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:56.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:141:0) after 1m0s
[01:30:56.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:141:0)
[01:30:56.912] Timer tick, asking Buildbucket for the build status
[01:30:57.000] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:57.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:142:0) after 1m0s
[01:31:57.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:142:0)
[01:31:57.065] Timer tick, asking Buildbucket for the build status
[01:31:57.115] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:57.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:143:0) after 1m0s
[01:32:57.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:143:0)
[01:32:57.142] Timer tick, asking Buildbucket for the build status
[01:32:57.192] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:57.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:144:0) after 1m0s
[01:33:57.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:144:0)
[01:33:57.219] Timer tick, asking Buildbucket for the build status
[01:33:57.279] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:57.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:145:0) after 1m0s
[01:34:57.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:145:0)
[01:34:57.304] Timer tick, asking Buildbucket for the build status
[01:34:57.375] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:57.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:146:0) after 1m0s
[01:35:57.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:146:0)
[01:35:57.451] Timer tick, asking Buildbucket for the build status
[01:35:57.493] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:57.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:147:0) after 1m0s
[01:36:57.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:147:0)
[01:36:57.514] Timer tick, asking Buildbucket for the build status
[01:36:57.559] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:57.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:148:0) after 1m0s
[01:37:57.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:148:0)
[01:37:57.613] Timer tick, asking Buildbucket for the build status
[01:37:57.976] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:57.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:149:0) after 1m0s
[01:38:57.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:149:0)
[01:38:57.999] Timer tick, asking Buildbucket for the build status
[01:38:58.046] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:58.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:150:0) after 1m0s
[01:39:58.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:150:0)
[01:39:58.078] Timer tick, asking Buildbucket for the build status
[01:39:58.138] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:58.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:151:0) after 1m0s
[01:40:58.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:151:0)
[01:40:58.160] Timer tick, asking Buildbucket for the build status
[01:40:58.241] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:58.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:152:0) after 1m0s
[01:41:58.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:152:0)
[01:41:58.261] Timer tick, asking Buildbucket for the build status
[01:41:58.415] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:58.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:153:0) after 1m0s
[01:42:58.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:153:0)
[01:42:58.473] Timer tick, asking Buildbucket for the build status
[01:42:58.567] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:58.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:154:0) after 1m0s
[01:43:58.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:154:0)
[01:43:58.589] Timer tick, asking Buildbucket for the build status
[01:43:58.660] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:58.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:155:0) after 1m0s
[01:44:58.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:155:0)
[01:44:58.698] Timer tick, asking Buildbucket for the build status
[01:44:58.894] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:58.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:156:0) after 1m0s
[01:45:58.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:156:0)
[01:45:58.968] Timer tick, asking Buildbucket for the build status
[01:45:59.065] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:59.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:157:0) after 1m0s
[01:46:59.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:157:0)
[01:46:59.103] Timer tick, asking Buildbucket for the build status
[01:46:59.214] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:59.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:158:0) after 1m0s
[01:47:59.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:158:0)
[01:47:59.243] Timer tick, asking Buildbucket for the build status
[01:47:59.320] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:59.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:159:0) after 1m0s
[01:48:59.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:159:0)
[01:48:59.339] Timer tick, asking Buildbucket for the build status
[01:48:59.444] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:59.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:160:0) after 1m0s
[01:49:59.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:160:0)
[01:49:59.471] Timer tick, asking Buildbucket for the build status
[01:49:59.513] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:59.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:161:0) after 1m0s
[01:50:59.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:161:0)
[01:50:59.532] Timer tick, asking Buildbucket for the build status
[01:50:59.650] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:59.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:162:0) after 1m0s
[01:51:59.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:162:0)
[01:51:59.691] Timer tick, asking Buildbucket for the build status
[01:52:00.162] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:00.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:163:0) after 1m0s
[01:53:00.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:163:0)
[01:53:00.252] Timer tick, asking Buildbucket for the build status
[01:53:00.362] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:00.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:164:0) after 1m0s
[01:54:00.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:164:0)
[01:54:00.409] Timer tick, asking Buildbucket for the build status
[01:54:00.544] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:00.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:165:0) after 1m0s
[01:55:00.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:165:0)
[01:55:00.643] Timer tick, asking Buildbucket for the build status
[01:55:00.704] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:00.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:166:0) after 1m0s
[01:56:00.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:166:0)
[01:56:00.736] Timer tick, asking Buildbucket for the build status
[01:56:00.791] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:00.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:167:0) after 1m0s
[01:57:00.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:167:0)
[01:57:00.875] Timer tick, asking Buildbucket for the build status
[01:57:00.943] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:00.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:168:0) after 1m0s
[01:58:00.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:168:0)
[01:58:00.967] Timer tick, asking Buildbucket for the build status
[01:58:01.015] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:01.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:169:0) after 1m0s
[01:59:01.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:169:0)
[01:59:01.058] Timer tick, asking Buildbucket for the build status
[01:59:01.124] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:01.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:170:0) after 1m0s
[02:00:01.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:170:0)
[02:00:01.157] Timer tick, asking Buildbucket for the build status
[02:00:01.359] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:01.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:171:0) after 1m0s
[02:01:01.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:171:0)
[02:01:01.389] Timer tick, asking Buildbucket for the build status
[02:01:01.466] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:01.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:172:0) after 1m0s
[02:02:01.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:172:0)
[02:02:01.493] Timer tick, asking Buildbucket for the build status
[02:02:01.584] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:01.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:173:0) after 1m0s
[02:03:01.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:173:0)
[02:03:01.607] Timer tick, asking Buildbucket for the build status
[02:03:01.670] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:01.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:174:0) after 1m0s
[02:04:01.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:174:0)
[02:04:01.695] Timer tick, asking Buildbucket for the build status
[02:04:01.784] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:01.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:175:0) after 1m0s
[02:05:01.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:175:0)
[02:05:01.923] Timer tick, asking Buildbucket for the build status
[02:05:01.994] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:01.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:176:0) after 1m0s
[02:06:02.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:176:0)
[02:06:02.022] Timer tick, asking Buildbucket for the build status
[02:06:02.106] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:02.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:177:0) after 1m0s
[02:07:02.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:177:0)
[02:07:02.136] Timer tick, asking Buildbucket for the build status
[02:07:02.188] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:02.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:178:0) after 1m0s
[02:08:02.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:178:0)
[02:08:02.260] Timer tick, asking Buildbucket for the build status
[02:08:02.534] Build 8924133303726075328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:02.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089563365943910224:179:0) after 1m0s
[02:09:00.016] Received PubSub notification, asking Buildbucket for the build status
[02:09:00.049] Build 8924133303726075328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:09:00.049] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1547690938778846",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547680294258820",
"id": "8924133303726075328",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
"project": "nacl",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3996-e2326f7\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-lcw3\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 7018, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64\", \"got_revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547680294258820, \"tags\": [\"builder:linux-pnacl-x86_64\", \"buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089563365943910224\", \"scheduler_job_id:nacl/linux-pnacl-x86_64\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8924133303726075328\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-lcw3\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1547680315627622",
"status": "COMPLETED",
"status_changed_ts": "1547690939594359",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7018",
"builder:linux-pnacl-x86_64",
"buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089563365943910224",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_dimension:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.nacl.toolchain",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7018",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8924133303726075328",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:linux-pnacl-x86_64",
"swarming_tag:buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
"swarming_tag:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924133303726075328/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.nacl.toolchain",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9089563365943910224",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:e1d5da8663c0550abd5affb209a08bfdb819ce3f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4271c0af684c1310",
"user_agent:luci-scheduler"
],
"updated_ts": "1547690939595841",
"url": "https://ci.chromium.org/b/8924133303726075328",
"utcnow_ts": "1547690940040369"
}
}
[02:09:00.049] Invocation finished in 2h57m27.28867462s with status SUCCEEDED