[23:11:32.232] New invocation is queued and will start shortly
[23:11:33.514] Starting the invocation (attempt 1)
[23:11:33.555] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:11:33.555] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:11:33.555] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9089563366515355312",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-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-tests-x86_64",
"scheduler_invocation_id:9089563366515355312",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-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:36.605] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547680293709507",
"id": "8924133304301595216",
"lease_expiration_ts": "1547831495330688",
"lease_key": "1770836768",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-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": "1547680296357434",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5056",
"builder:linux-pnacl-x86_64-tests-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:9089563366515355312",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_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-tests-x86_64/5056",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8924133304301595216",
"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-tests-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_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_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/8924133304301595216/+/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:9089563366515355312",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-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:4271c0afac9a6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1547680296357534",
"url": "https://ci.chromium.org/b/8924133304301595216",
"utcnow_ts": "1547680296593737"
}
}
[23:11:36.605] Task URL: https://ci.chromium.org/b/8924133304301595216
[23:11:36.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:2:0) after 1m0s
[23:12:07.387] Received PubSub notification, asking Buildbucket for the build status
[23:12:07.446] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:37.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:2:0)
[23:12:37.557] Timer tick, asking Buildbucket for the build status
[23:12:37.652] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:37.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:4:0) after 1m0s
[23:13:37.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:4:0)
[23:13:37.770] Timer tick, asking Buildbucket for the build status
[23:13:37.811] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:37.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:5:0) after 1m0s
[23:14:37.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:5:0)
[23:14:37.840] Timer tick, asking Buildbucket for the build status
[23:14:37.918] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:37.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:6:0) after 1m0s
[23:15:37.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:6:0)
[23:15:37.933] Timer tick, asking Buildbucket for the build status
[23:15:38.001] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:38.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:7:0) after 1m0s
[23:16:38.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:7:0)
[23:16:38.022] Timer tick, asking Buildbucket for the build status
[23:16:38.114] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:38.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:8:0) after 1m0s
[23:17:38.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:8:0)
[23:17:38.136] Timer tick, asking Buildbucket for the build status
[23:17:38.199] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:38.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:9:0) after 1m0s
[23:18:38.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:9:0)
[23:18:38.234] Timer tick, asking Buildbucket for the build status
[23:18:38.298] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:38.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:10:0) after 1m0s
[23:19:38.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:10:0)
[23:19:38.319] Timer tick, asking Buildbucket for the build status
[23:19:38.393] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:38.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:11:0) after 1m0s
[23:20:38.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:11:0)
[23:20:38.412] Timer tick, asking Buildbucket for the build status
[23:20:38.512] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:38.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:12:0) after 1m0s
[23:21:38.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:12:0)
[23:21:38.527] Timer tick, asking Buildbucket for the build status
[23:21:38.666] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:38.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:13:0) after 1m0s
[23:22:38.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:13:0)
[23:22:38.691] Timer tick, asking Buildbucket for the build status
[23:22:38.811] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:38.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:14:0) after 1m0s
[23:23:38.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:14:0)
[23:23:38.829] Timer tick, asking Buildbucket for the build status
[23:23:38.884] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:38.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:15:0) after 1m0s
[23:24:38.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:15:0)
[23:24:38.915] Timer tick, asking Buildbucket for the build status
[23:24:38.960] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:38.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:16:0) after 1m0s
[23:25:38.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:16:0)
[23:25:38.978] Timer tick, asking Buildbucket for the build status
[23:25:39.126] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:39.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:17:0) after 1m0s
[23:26:39.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:17:0)
[23:26:39.149] Timer tick, asking Buildbucket for the build status
[23:26:39.186] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:39.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:18:0) after 1m0s
[23:27:39.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:18:0)
[23:27:39.207] Timer tick, asking Buildbucket for the build status
[23:27:39.252] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:39.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:19:0) after 1m0s
[23:28:39.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:19:0)
[23:28:39.268] Timer tick, asking Buildbucket for the build status
[23:28:39.346] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:39.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:20:0) after 1m0s
[23:29:39.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:20:0)
[23:29:39.377] Timer tick, asking Buildbucket for the build status
[23:29:39.421] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:39.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:21:0) after 1m0s
[23:30:39.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:21:0)
[23:30:39.441] Timer tick, asking Buildbucket for the build status
[23:30:39.494] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:39.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:22:0) after 1m0s
[23:31:39.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:22:0)
[23:31:39.513] Timer tick, asking Buildbucket for the build status
[23:31:39.582] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:39.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:23:0) after 1m0s
[23:32:39.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:23:0)
[23:32:39.600] Timer tick, asking Buildbucket for the build status
[23:32:39.664] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:39.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:24:0) after 1m0s
[23:33:39.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:24:0)
[23:33:39.680] Timer tick, asking Buildbucket for the build status
[23:33:39.738] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:39.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:25:0) after 1m0s
[23:34:39.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:25:0)
[23:34:39.760] Timer tick, asking Buildbucket for the build status
[23:34:39.871] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:39.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:26:0) after 1m0s
[23:35:39.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:26:0)
[23:35:39.952] Timer tick, asking Buildbucket for the build status
[23:35:40.038] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:40.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:27:0) after 1m0s
[23:36:40.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:27:0)
[23:36:40.590] Timer tick, asking Buildbucket for the build status
[23:36:40.658] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:40.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:28:0) after 1m0s
[23:37:40.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:28:0)
[23:37:40.680] Timer tick, asking Buildbucket for the build status
[23:37:40.745] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:40.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:29:0) after 1m0s
[23:38:40.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:29:0)
[23:38:40.767] Timer tick, asking Buildbucket for the build status
[23:38:40.857] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:30:0) after 1m0s
[23:39:40.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:30:0)
[23:39:40.876] Timer tick, asking Buildbucket for the build status
[23:39:40.992] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:40.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:31:0) after 1m0s
[23:40:41.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:31:0)
[23:40:41.052] Timer tick, asking Buildbucket for the build status
[23:40:41.195] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:41.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:32:0) after 1m0s
[23:41:41.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:32:0)
[23:41:41.222] Timer tick, asking Buildbucket for the build status
[23:41:41.258] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:33:0) after 1m0s
[23:42:41.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:33:0)
[23:42:41.616] Timer tick, asking Buildbucket for the build status
[23:42:41.686] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:41.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:34:0) after 1m0s
[23:43:41.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:34:0)
[23:43:41.745] Timer tick, asking Buildbucket for the build status
[23:43:41.791] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:41.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:35:0) after 1m0s
[23:44:41.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:35:0)
[23:44:41.921] Timer tick, asking Buildbucket for the build status
[23:44:42.022] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:42.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:36:0) after 1m0s
[23:45:42.122] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:36:0)
[23:45:42.122] Timer tick, asking Buildbucket for the build status
[23:45:42.163] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:42.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:37:0) after 1m0s
[23:46:42.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:37:0)
[23:46:42.201] Timer tick, asking Buildbucket for the build status
[23:46:42.257] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:42.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:38:0) after 1m0s
[23:47:42.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:38:0)
[23:47:42.288] Timer tick, asking Buildbucket for the build status
[23:47:42.328] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:42.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:39:0) after 1m0s
[23:48:42.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:39:0)
[23:48:42.345] Timer tick, asking Buildbucket for the build status
[23:48:42.441] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:42.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:40:0) after 1m0s
[23:49:42.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:40:0)
[23:49:42.578] Timer tick, asking Buildbucket for the build status
[23:49:42.641] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:42.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:41:0) after 1m0s
[23:50:42.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:41:0)
[23:50:42.657] Timer tick, asking Buildbucket for the build status
[23:50:42.864] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:42.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:42:0) after 1m0s
[23:51:42.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:42:0)
[23:51:42.898] Timer tick, asking Buildbucket for the build status
[23:51:42.948] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:42.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:43:0) after 1m0s
[23:52:43.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:43:0)
[23:52:43.013] Timer tick, asking Buildbucket for the build status
[23:52:43.081] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:43.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:44:0) after 1m0s
[23:53:43.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:44:0)
[23:53:43.104] Timer tick, asking Buildbucket for the build status
[23:53:43.141] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:43.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:45:0) after 1m0s
[23:54:43.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:45:0)
[23:54:43.162] Timer tick, asking Buildbucket for the build status
[23:54:43.244] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:43.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:46:0) after 1m0s
[23:55:43.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:46:0)
[23:55:43.334] Timer tick, asking Buildbucket for the build status
[23:55:43.368] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:43.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:47:0) after 1m0s
[23:56:43.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:47:0)
[23:56:43.400] Timer tick, asking Buildbucket for the build status
[23:56:43.436] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:43.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:48:0) after 1m0s
[23:57:43.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:48:0)
[23:57:43.505] Timer tick, asking Buildbucket for the build status
[23:57:43.546] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:43.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:49:0) after 1m0s
[23:58:43.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:49:0)
[23:58:43.573] Timer tick, asking Buildbucket for the build status
[23:58:43.653] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:43.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:50:0) after 1m0s
[23:59:43.746] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:50:0)
[23:59:43.746] Timer tick, asking Buildbucket for the build status
[23:59:43.796] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:43.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:51:0) after 1m0s
[00:00:44.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:51:0)
[00:00:44.192] Timer tick, asking Buildbucket for the build status
[00:00:44.264] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:44.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:52:0) after 1m0s
[00:01:44.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:52:0)
[00:01:44.309] Timer tick, asking Buildbucket for the build status
[00:01:44.415] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:44.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:53:0) after 1m0s
[00:02:44.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:53:0)
[00:02:44.471] Timer tick, asking Buildbucket for the build status
[00:02:44.565] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:44.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:54:0) after 1m0s
[00:03:44.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:54:0)
[00:03:44.678] Timer tick, asking Buildbucket for the build status
[00:03:44.970] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:44.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:55:0) after 1m0s
[00:04:45.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:55:0)
[00:04:45.018] Timer tick, asking Buildbucket for the build status
[00:04:45.162] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:45.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:56:0) after 1m0s
[00:05:45.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:56:0)
[00:05:45.182] Timer tick, asking Buildbucket for the build status
[00:05:45.409] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:45.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:57:0) after 1m0s
[00:06:45.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:57:0)
[00:06:45.936] Timer tick, asking Buildbucket for the build status
[00:06:45.975] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:45.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:58:0) after 1m0s
[00:07:45.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:58:0)
[00:07:45.997] Timer tick, asking Buildbucket for the build status
[00:07:46.370] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:46.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:59:0) after 1m0s
[00:08:46.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:59:0)
[00:08:46.508] Timer tick, asking Buildbucket for the build status
[00:08:46.591] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:46.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:60:0) after 1m0s
[00:09:46.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:60:0)
[00:09:46.609] Timer tick, asking Buildbucket for the build status
[00:09:46.679] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:46.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:61:0) after 1m0s
[00:10:46.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:61:0)
[00:10:46.700] Timer tick, asking Buildbucket for the build status
[00:10:46.750] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:62:0) after 1m0s
[00:11:46.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:62:0)
[00:11:46.769] Timer tick, asking Buildbucket for the build status
[00:11:46.817] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:46.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:63:0) after 1m0s
[00:12:46.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:63:0)
[00:12:46.881] Timer tick, asking Buildbucket for the build status
[00:12:46.930] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:46.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:64:0) after 1m0s
[00:13:46.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:64:0)
[00:13:46.950] Timer tick, asking Buildbucket for the build status
[00:13:47.012] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:47.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:65:0) after 1m0s
[00:14:47.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:65:0)
[00:14:47.030] Timer tick, asking Buildbucket for the build status
[00:14:47.071] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:47.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:66:0) after 1m0s
[00:15:47.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:66:0)
[00:15:47.091] Timer tick, asking Buildbucket for the build status
[00:15:47.184] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:67:0) after 1m0s
[00:16:47.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:67:0)
[00:16:47.203] Timer tick, asking Buildbucket for the build status
[00:16:47.242] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:47.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:68:0) after 1m0s
[00:17:47.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:68:0)
[00:17:47.263] Timer tick, asking Buildbucket for the build status
[00:17:47.337] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:47.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:69:0) after 1m0s
[00:18:47.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:69:0)
[00:18:47.355] Timer tick, asking Buildbucket for the build status
[00:18:47.448] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:47.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:70:0) after 1m0s
[00:19:47.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:70:0)
[00:19:47.475] Timer tick, asking Buildbucket for the build status
[00:19:47.706] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:47.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:71:0) after 1m0s
[00:20:47.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:71:0)
[00:20:47.730] Timer tick, asking Buildbucket for the build status
[00:20:47.780] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:47.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:72:0) after 1m0s
[00:21:47.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:72:0)
[00:21:47.803] Timer tick, asking Buildbucket for the build status
[00:21:47.900] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:47.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:73:0) after 1m0s
[00:22:47.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:73:0)
[00:22:47.923] Timer tick, asking Buildbucket for the build status
[00:22:47.993] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:47.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:74:0) after 1m0s
[00:23:48.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:74:0)
[00:23:48.014] Timer tick, asking Buildbucket for the build status
[00:23:48.159] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:48.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:75:0) after 1m0s
[00:24:48.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:75:0)
[00:24:48.189] Timer tick, asking Buildbucket for the build status
[00:24:48.271] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:76:0) after 1m0s
[00:25:48.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:76:0)
[00:25:48.316] Timer tick, asking Buildbucket for the build status
[00:25:48.416] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:77:0) after 1m0s
[00:26:48.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:77:0)
[00:26:48.437] Timer tick, asking Buildbucket for the build status
[00:26:48.519] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:48.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:78:0) after 1m0s
[00:27:48.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:78:0)
[00:27:48.537] Timer tick, asking Buildbucket for the build status
[00:27:48.592] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:48.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:79:0) after 1m0s
[00:28:48.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:79:0)
[00:28:48.612] Timer tick, asking Buildbucket for the build status
[00:28:48.665] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:48.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:80:0) after 1m0s
[00:29:48.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:80:0)
[00:29:48.685] Timer tick, asking Buildbucket for the build status
[00:29:48.728] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:48.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:81:0) after 1m0s
[00:30:48.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:81:0)
[00:30:48.747] Timer tick, asking Buildbucket for the build status
[00:30:48.783] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:48.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:82:0) after 1m0s
[00:31:48.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:82:0)
[00:31:48.801] Timer tick, asking Buildbucket for the build status
[00:31:48.841] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:48.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:83:0) after 1m0s
[00:32:48.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:83:0)
[00:32:48.840] Timer tick, asking Buildbucket for the build status
[00:32:48.904] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:48.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:84:0) after 1m0s
[00:33:48.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:84:0)
[00:33:48.925] Timer tick, asking Buildbucket for the build status
[00:33:49.035] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:49.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:85:0) after 1m0s
[00:34:49.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:85:0)
[00:34:49.057] Timer tick, asking Buildbucket for the build status
[00:34:49.145] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:49.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:86:0) after 1m0s
[00:35:49.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:86:0)
[00:35:49.171] Timer tick, asking Buildbucket for the build status
[00:35:49.217] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:49.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:87:0) after 1m0s
[00:36:49.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:87:0)
[00:36:49.240] Timer tick, asking Buildbucket for the build status
[00:36:49.308] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:49.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:88:0) after 1m0s
[00:37:49.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:88:0)
[00:37:49.504] Timer tick, asking Buildbucket for the build status
[00:37:49.557] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:49.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:89:0) after 1m0s
[00:38:49.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:89:0)
[00:38:49.671] Timer tick, asking Buildbucket for the build status
[00:38:49.786] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:49.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:90:0) after 1m0s
[00:39:49.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:90:0)
[00:39:49.805] Timer tick, asking Buildbucket for the build status
[00:39:49.893] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:49.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:91:0) after 1m0s
[00:40:49.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:91:0)
[00:40:49.910] Timer tick, asking Buildbucket for the build status
[00:40:49.983] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:49.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:92:0) after 1m0s
[00:41:50.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:92:0)
[00:41:50.001] Timer tick, asking Buildbucket for the build status
[00:41:50.041] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:50.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:93:0) after 1m0s
[00:42:50.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:93:0)
[00:42:50.072] Timer tick, asking Buildbucket for the build status
[00:42:50.112] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:50.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:94:0) after 1m0s
[00:43:50.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:94:0)
[00:43:50.172] Timer tick, asking Buildbucket for the build status
[00:43:50.235] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:50.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:95:0) after 1m0s
[00:44:50.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:95:0)
[00:44:50.263] Timer tick, asking Buildbucket for the build status
[00:44:50.353] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:50.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:96:0) after 1m0s
[00:45:50.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:96:0)
[00:45:50.379] Timer tick, asking Buildbucket for the build status
[00:45:50.467] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:50.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:97:0) after 1m0s
[00:46:50.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:97:0)
[00:46:50.487] Timer tick, asking Buildbucket for the build status
[00:46:50.535] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:50.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:98:0) after 1m0s
[00:47:50.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:98:0)
[00:47:50.559] Timer tick, asking Buildbucket for the build status
[00:47:50.653] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:50.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:99:0) after 1m0s
[00:48:50.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:99:0)
[00:48:50.690] Timer tick, asking Buildbucket for the build status
[00:48:50.735] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:50.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:100:0) after 1m0s
[00:49:50.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:100:0)
[00:49:50.752] Timer tick, asking Buildbucket for the build status
[00:49:50.814] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:50.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:101:0) after 1m0s
[00:50:50.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:101:0)
[00:50:50.833] Timer tick, asking Buildbucket for the build status
[00:50:50.988] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:102:0) after 1m0s
[00:51:51.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:102:0)
[00:51:51.015] Timer tick, asking Buildbucket for the build status
[00:51:51.055] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:51.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:103:0) after 1m0s
[00:52:51.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:103:0)
[00:52:51.087] Timer tick, asking Buildbucket for the build status
[00:52:51.157] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:51.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:104:0) after 1m0s
[00:53:51.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:104:0)
[00:53:51.189] Timer tick, asking Buildbucket for the build status
[00:53:51.349] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:51.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:105:0) after 1m0s
[00:54:51.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:105:0)
[00:54:51.389] Timer tick, asking Buildbucket for the build status
[00:54:51.432] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:51.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:106:0) after 1m0s
[00:55:51.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:106:0)
[00:55:51.454] Timer tick, asking Buildbucket for the build status
[00:55:51.504] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:51.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:107:0) after 1m0s
[00:56:51.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:107:0)
[00:56:51.531] Timer tick, asking Buildbucket for the build status
[00:56:51.691] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:51.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:108:0) after 1m0s
[00:57:51.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:108:0)
[00:57:51.712] Timer tick, asking Buildbucket for the build status
[00:57:51.827] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:51.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:109:0) after 1m0s
[00:58:51.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:109:0)
[00:58:51.847] Timer tick, asking Buildbucket for the build status
[00:58:52.088] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:52.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:110:0) after 1m0s
[00:59:52.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:110:0)
[00:59:52.128] Timer tick, asking Buildbucket for the build status
[00:59:52.177] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:52.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:111:0) after 1m0s
[01:00:52.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:111:0)
[01:00:52.196] Timer tick, asking Buildbucket for the build status
[01:00:52.330] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:52.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:112:0) after 1m0s
[01:01:52.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:112:0)
[01:01:52.390] Timer tick, asking Buildbucket for the build status
[01:01:52.642] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:52.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:113:0) after 1m0s
[01:02:52.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:113:0)
[01:02:52.685] Timer tick, asking Buildbucket for the build status
[01:02:52.736] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:52.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:114:0) after 1m0s
[01:03:52.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:114:0)
[01:03:52.758] Timer tick, asking Buildbucket for the build status
[01:03:52.823] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:52.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:115:0) after 1m0s
[01:04:52.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:115:0)
[01:04:52.857] Timer tick, asking Buildbucket for the build status
[01:04:52.955] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:52.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:116:0) after 1m0s
[01:05:52.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:116:0)
[01:05:52.977] Timer tick, asking Buildbucket for the build status
[01:05:53.055] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:53.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:117:0) after 1m0s
[01:06:53.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:117:0)
[01:06:53.079] Timer tick, asking Buildbucket for the build status
[01:06:53.296] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:53.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:118:0) after 1m0s
[01:07:53.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:118:0)
[01:07:53.320] Timer tick, asking Buildbucket for the build status
[01:07:53.832] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:53.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:119:0) after 1m0s
[01:08:53.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:119:0)
[01:08:53.856] Timer tick, asking Buildbucket for the build status
[01:08:53.895] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:53.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:120:0) after 1m0s
[01:09:53.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:120:0)
[01:09:53.917] Timer tick, asking Buildbucket for the build status
[01:09:54.041] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:54.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:121:0) after 1m0s
[01:10:54.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:121:0)
[01:10:54.059] Timer tick, asking Buildbucket for the build status
[01:10:54.214] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:54.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:122:0) after 1m0s
[01:11:54.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:122:0)
[01:11:54.236] Timer tick, asking Buildbucket for the build status
[01:11:54.360] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:54.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:123:0) after 1m0s
[01:12:54.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:123:0)
[01:12:54.392] Timer tick, asking Buildbucket for the build status
[01:12:54.450] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:54.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:124:0) after 1m0s
[01:13:54.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:124:0)
[01:13:54.470] Timer tick, asking Buildbucket for the build status
[01:13:54.513] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:54.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:125:0) after 1m0s
[01:14:54.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:125:0)
[01:14:54.533] Timer tick, asking Buildbucket for the build status
[01:14:54.593] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:54.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:126:0) after 1m0s
[01:15:54.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:126:0)
[01:15:54.638] Timer tick, asking Buildbucket for the build status
[01:15:54.728] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:54.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:127:0) after 1m0s
[01:16:54.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:127:0)
[01:16:54.752] Timer tick, asking Buildbucket for the build status
[01:16:54.846] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:54.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:128:0) after 1m0s
[01:17:54.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:128:0)
[01:17:54.866] Timer tick, asking Buildbucket for the build status
[01:17:54.936] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:54.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:129:0) after 1m0s
[01:18:54.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:129:0)
[01:18:54.970] Timer tick, asking Buildbucket for the build status
[01:18:55.222] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:55.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:130:0) after 1m0s
[01:19:55.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:130:0)
[01:19:55.249] Timer tick, asking Buildbucket for the build status
[01:19:55.364] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:131:0) after 1m0s
[01:20:55.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:131:0)
[01:20:55.397] Timer tick, asking Buildbucket for the build status
[01:20:55.517] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:132:0) after 1m0s
[01:21:55.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:132:0)
[01:21:55.540] Timer tick, asking Buildbucket for the build status
[01:21:55.652] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:55.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:133:0) after 1m0s
[01:22:55.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:133:0)
[01:22:55.673] Timer tick, asking Buildbucket for the build status
[01:22:55.798] Build 8924133304301595216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:55.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089563366515355312:134:0) after 1m0s
[01:23:37.700] Received PubSub notification, asking Buildbucket for the build status
[01:23:37.743] Build 8924133304301595216: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:23:37.743] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "AUTO",
"completed_ts": "1547688216373468",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547680293709507",
"failure_reason": "BUILD_FAILURE",
"id": "8924133304301595216",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b_sz\"}, \"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-15zg\"], \"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\": 5056, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-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\": 1547680293709507, \"tags\": [\"builder:linux-pnacl-x86_64-tests-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:9089563366515355312\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8924133304301595216\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-15zg\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1547680308987654",
"status": "COMPLETED",
"status_changed_ts": "1547688217034790",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5056",
"builder:linux-pnacl-x86_64-tests-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:9089563366515355312",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
"swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_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-tests-x86_64/5056",
"swarming_tag:buildbucket_bucket:nacl/toolchain",
"swarming_tag:buildbucket_build_id:8924133304301595216",
"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-tests-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_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_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/8924133304301595216/+/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:9089563366515355312",
"swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-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:4271c0afac9a6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1547688217036222",
"url": "https://ci.chromium.org/b/8924133304301595216",
"utcnow_ts": "1547688217734325"
}
}
[01:23:37.743] Invocation finished in 2h12m5.52769812s with status FAILED