buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.nacl.toolchain"
  builder: "linux-pnacl-x86_64"
>

• 883ebe523b7b086567c2e70d260667959ea19f25 (4 years ago)

{
  "branch": "refs/heads/master",
  "repository": "https://chromium.googlesource.com/native_client/src/native_client.git",
  "revision": "883ebe523b7b086567c2e70d260667959ea19f25"
}

• buildset:commit/git/883ebe523b7b086567c2e70d260667959ea19f25
• buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/883ebe523b7b086567c2e70d260667959ea19f25
• gitiles_ref:refs/heads/master

[23:41:36.305] New invocation is queued and will start shortly
[23:41:37.855] Starting the invocation (attempt 1)
[23:41:37.889] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:41:37.889] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:41:37.889] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9032575982942540736",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"883ebe523b7b086567c2e70d260667959ea19f25\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@883ebe523b7b086567c2e70d260667959ea19f25\",\"title\":\"883ebe523b7b086567c2e70d260667959ea19f25\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/883ebe523b7b086567c2e70d260667959ea19f25\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"883ebe523b7b086567c2e70d260667959ea19f25\"}}",
  "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:9032575982942540736",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/883ebe523b7b086567c2e70d260667959ea19f25",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/883ebe523b7b086567c2e70d260667959ea19f25",
    "gitiles_ref:refs/heads/master"
  ]
}
[23:41:38.466] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1602027697975420",
    "id": "8867145920526859104",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@883ebe523b7b086567c2e70d260667959ea19f25\", \"title\": \"883ebe523b7b086567c2e70d260667959ea19f25\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/883ebe523b7b086567c2e70d260667959ea19f25\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1602027698327212",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7077",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/883ebe523b7b086567c2e70d260667959ea19f25",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/883ebe523b7b086567c2e70d260667959ea19f25",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9032575982942540736",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8867145920526859104/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1602027698327353",
    "url": "https://ci.chromium.org/b/8867145920526859104",
    "utcnow_ts": "1602027698459035"
  }
}
[23:41:38.466] Task URL: https://ci.chromium.org/b/8867145920526859104
[23:41:38.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:2:0) after 1m0s
[23:42:33.930] Received PubSub notification, asking Buildbucket for the build status
[23:42:34.000] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:38.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:2:0)
[23:42:38.573] Timer tick, asking Buildbucket for the build status
[23:42:38.683] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:38.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:4:0) after 1m0s
[23:43:38.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:4:0)
[23:43:38.698] Timer tick, asking Buildbucket for the build status
[23:43:39.010] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:39.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:5:0) after 1m0s
[23:44:39.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:5:0)
[23:44:39.082] Timer tick, asking Buildbucket for the build status
[23:44:39.193] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:39.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:6:0) after 1m0s
[23:45:39.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:6:0)
[23:45:39.230] Timer tick, asking Buildbucket for the build status
[23:45:39.903] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:39.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:7:0) after 1m0s
[23:46:39.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:7:0)
[23:46:39.923] Timer tick, asking Buildbucket for the build status
[23:46:40.027] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:40.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:8:0) after 1m0s
[23:47:40.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:8:0)
[23:47:40.049] Timer tick, asking Buildbucket for the build status
[23:47:40.402] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:40.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:9:0) after 1m0s
[23:48:40.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:9:0)
[23:48:40.500] Timer tick, asking Buildbucket for the build status
[23:48:40.615] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:10:0) after 1m0s
[23:49:40.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:10:0)
[23:49:40.638] Timer tick, asking Buildbucket for the build status
[23:49:40.765] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:11:0) after 1m0s
[23:50:40.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:11:0)
[23:50:40.752] Timer tick, asking Buildbucket for the build status
[23:50:40.819] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:12:0) after 1m0s
[23:51:40.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:12:0)
[23:51:40.926] Timer tick, asking Buildbucket for the build status
[23:51:41.037] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:41.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:13:0) after 1m0s
[23:52:41.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:13:0)
[23:52:41.183] Timer tick, asking Buildbucket for the build status
[23:52:41.305] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:41.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:14:0) after 1m0s
[23:53:41.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:14:0)
[23:53:41.383] Timer tick, asking Buildbucket for the build status
[23:53:41.615] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:41.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:15:0) after 1m0s
[23:54:41.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:15:0)
[23:54:41.749] Timer tick, asking Buildbucket for the build status
[23:54:41.836] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:16:0) after 1m0s
[23:55:41.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:16:0)
[23:55:41.897] Timer tick, asking Buildbucket for the build status
[23:55:42.196] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:42.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:17:0) after 1m0s
[23:56:42.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:17:0)
[23:56:42.227] Timer tick, asking Buildbucket for the build status
[23:56:42.651] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:42.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:18:0) after 1m0s
[23:57:42.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:18:0)
[23:57:42.766] Timer tick, asking Buildbucket for the build status
[23:57:42.959] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:42.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:19:0) after 1m0s
[23:58:42.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:19:0)
[23:58:42.990] Timer tick, asking Buildbucket for the build status
[23:58:43.154] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:43.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:20:0) after 1m0s
[23:59:43.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:20:0)
[23:59:43.175] Timer tick, asking Buildbucket for the build status
[23:59:43.298] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:43.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:21:0) after 1m0s
[00:00:43.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:21:0)
[00:00:43.299] Timer tick, asking Buildbucket for the build status
[00:00:43.571] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:43.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:22:0) after 1m0s
[00:01:43.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:22:0)
[00:01:43.619] Timer tick, asking Buildbucket for the build status
[00:01:43.736] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:43.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:23:0) after 1m0s
[00:02:43.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:23:0)
[00:02:43.759] Timer tick, asking Buildbucket for the build status
[00:02:43.874] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:43.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:24:0) after 1m0s
[00:03:43.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:24:0)
[00:03:43.952] Timer tick, asking Buildbucket for the build status
[00:03:44.053] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:44.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:25:0) after 1m0s
[00:04:44.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:25:0)
[00:04:44.199] Timer tick, asking Buildbucket for the build status
[00:04:44.326] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:44.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:26:0) after 1m0s
[00:05:44.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:26:0)
[00:05:44.393] Timer tick, asking Buildbucket for the build status
[00:05:44.510] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:44.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:27:0) after 1m0s
[00:06:44.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:27:0)
[00:06:44.618] Timer tick, asking Buildbucket for the build status
[00:06:44.730] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:44.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:28:0) after 1m0s
[00:07:44.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:28:0)
[00:07:44.782] Timer tick, asking Buildbucket for the build status
[00:07:45.104] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:45.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:29:0) after 1m0s
[00:08:45.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:29:0)
[00:08:45.168] Timer tick, asking Buildbucket for the build status
[00:08:45.628] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:45.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:30:0) after 1m0s
[00:09:45.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:30:0)
[00:09:45.704] Timer tick, asking Buildbucket for the build status
[00:09:45.766] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:45.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:31:0) after 1m0s
[00:10:45.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:31:0)
[00:10:45.965] Timer tick, asking Buildbucket for the build status
[00:10:46.064] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:32:0) after 1m0s
[00:11:46.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:32:0)
[00:11:46.175] Timer tick, asking Buildbucket for the build status
[00:11:46.302] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:46.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:33:0) after 1m0s
[00:12:46.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:33:0)
[00:12:46.375] Timer tick, asking Buildbucket for the build status
[00:12:46.707] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:46.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:34:0) after 1m0s
[00:13:46.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:34:0)
[00:13:46.862] Timer tick, asking Buildbucket for the build status
[00:13:46.914] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:46.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:35:0) after 1m0s
[00:14:47.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:35:0)
[00:14:47.018] Timer tick, asking Buildbucket for the build status
[00:14:47.096] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:47.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:36:0) after 1m0s
[00:15:47.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:36:0)
[00:15:47.116] Timer tick, asking Buildbucket for the build status
[00:15:47.177] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:37:0) after 1m0s
[00:16:47.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:37:0)
[00:16:47.250] Timer tick, asking Buildbucket for the build status
[00:16:47.356] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:47.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:38:0) after 1m0s
[00:17:47.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:38:0)
[00:17:47.382] Timer tick, asking Buildbucket for the build status
[00:17:47.449] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:47.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:39:0) after 1m0s
[00:18:47.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:39:0)
[00:18:47.528] Timer tick, asking Buildbucket for the build status
[00:18:47.584] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:47.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:40:0) after 1m0s
[00:19:47.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:40:0)
[00:19:47.806] Timer tick, asking Buildbucket for the build status
[00:19:48.032] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:48.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:41:0) after 1m0s
[00:20:48.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:41:0)
[00:20:48.064] Timer tick, asking Buildbucket for the build status
[00:20:48.281] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:48.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:42:0) after 1m0s
[00:21:48.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:42:0)
[00:21:48.307] Timer tick, asking Buildbucket for the build status
[00:21:48.714] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:48.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:43:0) after 1m0s
[00:22:48.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:43:0)
[00:22:48.823] Timer tick, asking Buildbucket for the build status
[00:22:48.890] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:48.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:44:0) after 1m0s
[00:23:49.050] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:44:0)
[00:23:49.050] Timer tick, asking Buildbucket for the build status
[00:23:49.119] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:49.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:45:0) after 1m0s
[00:24:49.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:45:0)
[00:24:49.208] Timer tick, asking Buildbucket for the build status
[00:24:49.519] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:49.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:46:0) after 1m0s
[00:25:49.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:46:0)
[00:25:49.533] Timer tick, asking Buildbucket for the build status
[00:25:49.603] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:49.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:47:0) after 1m0s
[00:26:49.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:47:0)
[00:26:49.599] Timer tick, asking Buildbucket for the build status
[00:26:49.749] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:49.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:48:0) after 1m0s
[00:27:49.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:48:0)
[00:27:49.854] Timer tick, asking Buildbucket for the build status
[00:27:49.978] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:49.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:49:0) after 1m0s
[00:28:50.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:49:0)
[00:28:50.117] Timer tick, asking Buildbucket for the build status
[00:28:50.184] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:50.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:50:0) after 1m0s
[00:29:50.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:50:0)
[00:29:50.220] Timer tick, asking Buildbucket for the build status
[00:29:50.397] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:50.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:51:0) after 1m0s
[00:30:50.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:51:0)
[00:30:50.391] Timer tick, asking Buildbucket for the build status
[00:30:50.439] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:50.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:52:0) after 1m0s
[00:31:50.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:52:0)
[00:31:50.464] Timer tick, asking Buildbucket for the build status
[00:31:50.911] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:50.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:53:0) after 1m0s
[00:32:50.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:53:0)
[00:32:50.906] Timer tick, asking Buildbucket for the build status
[00:32:51.294] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:51.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:54:0) after 1m0s
[00:33:51.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:54:0)
[00:33:51.342] Timer tick, asking Buildbucket for the build status
[00:33:51.705] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:51.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:55:0) after 1m0s
[00:34:51.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:55:0)
[00:34:51.733] Timer tick, asking Buildbucket for the build status
[00:34:51.795] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:51.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:56:0) after 1m0s
[00:35:51.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:56:0)
[00:35:51.789] Timer tick, asking Buildbucket for the build status
[00:35:51.840] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:51.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:57:0) after 1m0s
[00:36:51.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:57:0)
[00:36:51.862] Timer tick, asking Buildbucket for the build status
[00:36:51.972] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:51.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:58:0) after 1m0s
[00:37:51.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:58:0)
[00:37:51.976] Timer tick, asking Buildbucket for the build status
[00:37:52.107] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:52.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:59:0) after 1m0s
[00:38:52.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:59:0)
[00:38:52.138] Timer tick, asking Buildbucket for the build status
[00:38:52.280] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:52.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:60:0) after 1m0s
[00:39:52.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:60:0)
[00:39:52.327] Timer tick, asking Buildbucket for the build status
[00:39:52.401] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:52.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:61:0) after 1m0s
[00:40:52.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:61:0)
[00:40:52.455] Timer tick, asking Buildbucket for the build status
[00:40:52.619] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:52.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:62:0) after 1m0s
[00:41:52.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:62:0)
[00:41:52.644] Timer tick, asking Buildbucket for the build status
[00:41:52.761] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:52.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:63:0) after 1m0s
[00:42:52.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:63:0)
[00:42:52.857] Timer tick, asking Buildbucket for the build status
[00:42:52.934] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:52.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:64:0) after 1m0s
[00:43:52.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:64:0)
[00:43:52.963] Timer tick, asking Buildbucket for the build status
[00:43:53.025] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:53.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:65:0) after 1m0s
[00:44:53.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:65:0)
[00:44:53.024] Timer tick, asking Buildbucket for the build status
[00:44:53.090] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:53.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:66:0) after 1m0s
[00:45:53.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:66:0)
[00:45:53.134] Timer tick, asking Buildbucket for the build status
[00:45:53.201] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:53.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:67:0) after 1m0s
[00:46:53.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:67:0)
[00:46:53.206] Timer tick, asking Buildbucket for the build status
[00:46:53.701] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:53.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:68:0) after 1m0s
[00:47:53.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:68:0)
[00:47:53.770] Timer tick, asking Buildbucket for the build status
[00:47:54.070] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:54.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:69:0) after 1m0s
[00:48:54.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:69:0)
[00:48:54.117] Timer tick, asking Buildbucket for the build status
[00:48:54.321] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:54.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:70:0) after 1m0s
[00:49:54.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:70:0)
[00:49:54.340] Timer tick, asking Buildbucket for the build status
[00:49:54.462] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:54.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:71:0) after 1m0s
[00:50:54.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:71:0)
[00:50:54.487] Timer tick, asking Buildbucket for the build status
[00:50:55.488] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:55.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:72:0) after 1m0s
[00:51:55.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:72:0)
[00:51:55.564] Timer tick, asking Buildbucket for the build status
[00:51:55.674] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:55.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:73:0) after 1m0s
[00:52:55.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:73:0)
[00:52:55.757] Timer tick, asking Buildbucket for the build status
[00:52:55.871] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:55.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:74:0) after 1m0s
[00:53:55.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:74:0)
[00:53:55.947] Timer tick, asking Buildbucket for the build status
[00:53:56.067] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:56.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:75:0) after 1m0s
[00:54:56.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:75:0)
[00:54:56.218] Timer tick, asking Buildbucket for the build status
[00:54:56.378] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:56.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:76:0) after 1m0s
[00:55:56.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:76:0)
[00:55:56.388] Timer tick, asking Buildbucket for the build status
[00:55:56.488] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:56.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:77:0) after 1m0s
[00:56:56.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:77:0)
[00:56:56.526] Timer tick, asking Buildbucket for the build status
[00:56:56.658] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:56.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:78:0) after 1m0s
[00:57:56.715] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:78:0)
[00:57:56.715] Timer tick, asking Buildbucket for the build status
[00:57:56.840] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:56.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:79:0) after 1m0s
[00:58:56.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:79:0)
[00:58:56.911] Timer tick, asking Buildbucket for the build status
[00:58:57.006] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:57.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:80:0) after 1m0s
[00:59:57.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:80:0)
[00:59:57.028] Timer tick, asking Buildbucket for the build status
[00:59:57.152] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:57.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:81:0) after 1m0s
[01:00:57.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:81:0)
[01:00:57.182] Timer tick, asking Buildbucket for the build status
[01:00:59.698] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:59.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:82:0) after 1m0s
[01:01:59.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:82:0)
[01:01:59.740] Timer tick, asking Buildbucket for the build status
[01:01:59.884] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:59.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:83:0) after 1m0s
[01:02:59.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:83:0)
[01:02:59.936] Timer tick, asking Buildbucket for the build status
[01:03:00.246] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:00.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:84:0) after 1m0s
[01:04:00.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:84:0)
[01:04:00.344] Timer tick, asking Buildbucket for the build status
[01:04:00.449] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:00.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:85:0) after 1m0s
[01:05:01.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:85:0)
[01:05:01.532] Timer tick, asking Buildbucket for the build status
[01:05:01.635] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:01.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:86:0) after 1m0s
[01:06:01.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:86:0)
[01:06:01.720] Timer tick, asking Buildbucket for the build status
[01:06:01.901] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:01.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:87:0) after 1m0s
[01:07:01.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:87:0)
[01:07:01.892] Timer tick, asking Buildbucket for the build status
[01:07:02.013] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:02.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:88:0) after 1m0s
[01:08:02.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:88:0)
[01:08:02.112] Timer tick, asking Buildbucket for the build status
[01:08:02.292] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:02.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:89:0) after 1m0s
[01:09:02.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:89:0)
[01:09:02.337] Timer tick, asking Buildbucket for the build status
[01:09:02.431] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:02.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:90:0) after 1m0s
[01:10:02.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:90:0)
[01:10:02.456] Timer tick, asking Buildbucket for the build status
[01:10:02.728] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:02.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:91:0) after 1m0s
[01:11:02.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:91:0)
[01:11:02.752] Timer tick, asking Buildbucket for the build status
[01:11:02.944] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:02.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:92:0) after 1m0s
[01:12:02.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:92:0)
[01:12:02.932] Timer tick, asking Buildbucket for the build status
[01:12:03.044] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:03.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:93:0) after 1m0s
[01:13:03.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:93:0)
[01:13:03.130] Timer tick, asking Buildbucket for the build status
[01:13:03.220] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:03.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:94:0) after 1m0s
[01:14:03.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:94:0)
[01:14:03.329] Timer tick, asking Buildbucket for the build status
[01:14:03.582] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:03.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:95:0) after 1m0s
[01:15:03.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:95:0)
[01:15:03.682] Timer tick, asking Buildbucket for the build status
[01:15:04.644] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:04.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:96:0) after 1m0s
[01:16:04.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:96:0)
[01:16:04.676] Timer tick, asking Buildbucket for the build status
[01:16:05.178] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:05.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:97:0) after 1m0s
[01:17:05.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:97:0)
[01:17:05.238] Timer tick, asking Buildbucket for the build status
[01:17:05.357] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:05.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:98:0) after 1m0s
[01:18:05.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:98:0)
[01:18:05.438] Timer tick, asking Buildbucket for the build status
[01:18:05.723] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:05.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:99:0) after 1m0s
[01:19:05.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:99:0)
[01:19:05.779] Timer tick, asking Buildbucket for the build status
[01:19:05.839] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:05.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:100:0) after 1m0s
[01:20:05.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:100:0)
[01:20:05.917] Timer tick, asking Buildbucket for the build status
[01:20:06.048] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:06.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:101:0) after 1m0s
[01:21:06.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:101:0)
[01:21:06.101] Timer tick, asking Buildbucket for the build status
[01:21:06.380] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:06.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:102:0) after 1m0s
[01:22:06.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:102:0)
[01:22:06.410] Timer tick, asking Buildbucket for the build status
[01:22:06.508] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:06.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:103:0) after 1m0s
[01:23:07.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:103:0)
[01:23:07.232] Timer tick, asking Buildbucket for the build status
[01:23:07.524] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:07.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:104:0) after 1m0s
[01:24:07.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:104:0)
[01:24:07.598] Timer tick, asking Buildbucket for the build status
[01:24:07.882] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:07.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:105:0) after 1m0s
[01:25:08.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:105:0)
[01:25:08.190] Timer tick, asking Buildbucket for the build status
[01:25:08.271] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:08.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:106:0) after 1m0s
[01:26:08.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:106:0)
[01:26:08.369] Timer tick, asking Buildbucket for the build status
[01:26:08.440] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:08.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:107:0) after 1m0s
[01:27:08.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:107:0)
[01:27:08.568] Timer tick, asking Buildbucket for the build status
[01:27:08.696] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:08.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:108:0) after 1m0s
[01:28:08.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:108:0)
[01:28:08.749] Timer tick, asking Buildbucket for the build status
[01:28:08.900] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:08.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:109:0) after 1m0s
[01:29:08.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:109:0)
[01:29:08.943] Timer tick, asking Buildbucket for the build status
[01:29:09.056] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:09.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:110:0) after 1m0s
[01:30:09.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:110:0)
[01:30:09.172] Timer tick, asking Buildbucket for the build status
[01:30:09.283] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:09.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:111:0) after 1m0s
[01:31:09.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:111:0)
[01:31:09.311] Timer tick, asking Buildbucket for the build status
[01:31:09.399] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:09.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:112:0) after 1m0s
[01:32:09.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:112:0)
[01:32:09.497] Timer tick, asking Buildbucket for the build status
[01:32:09.643] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:09.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:113:0) after 1m0s
[01:33:09.660] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:113:0)
[01:33:09.660] Timer tick, asking Buildbucket for the build status
[01:33:09.808] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:09.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:114:0) after 1m0s
[01:34:09.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:114:0)
[01:34:09.979] Timer tick, asking Buildbucket for the build status
[01:34:10.149] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:10.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:115:0) after 1m0s
[01:35:10.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:115:0)
[01:35:10.245] Timer tick, asking Buildbucket for the build status
[01:35:10.339] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:10.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:116:0) after 1m0s
[01:36:10.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:116:0)
[01:36:10.371] Timer tick, asking Buildbucket for the build status
[01:36:10.462] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:10.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:117:0) after 1m0s
[01:37:10.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:117:0)
[01:37:10.490] Timer tick, asking Buildbucket for the build status
[01:37:10.613] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:10.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:118:0) after 1m0s
[01:38:10.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:118:0)
[01:38:10.622] Timer tick, asking Buildbucket for the build status
[01:38:10.702] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:10.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:119:0) after 1m0s
[01:39:10.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:119:0)
[01:39:10.742] Timer tick, asking Buildbucket for the build status
[01:39:10.797] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:10.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:120:0) after 1m0s
[01:40:10.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:120:0)
[01:40:10.896] Timer tick, asking Buildbucket for the build status
[01:40:11.310] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:11.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:121:0) after 1m0s
[01:41:11.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:121:0)
[01:41:11.347] Timer tick, asking Buildbucket for the build status
[01:41:11.515] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:11.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:122:0) after 1m0s
[01:42:11.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:122:0)
[01:42:11.615] Timer tick, asking Buildbucket for the build status
[01:42:11.706] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:11.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:123:0) after 1m0s
[01:43:11.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:123:0)
[01:43:11.727] Timer tick, asking Buildbucket for the build status
[01:43:11.786] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:11.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:124:0) after 1m0s
[01:44:11.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:124:0)
[01:44:11.813] Timer tick, asking Buildbucket for the build status
[01:44:12.193] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:12.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:125:0) after 1m0s
[01:45:12.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:125:0)
[01:45:12.264] Timer tick, asking Buildbucket for the build status
[01:45:12.435] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:12.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:126:0) after 1m0s
[01:46:12.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:126:0)
[01:46:12.486] Timer tick, asking Buildbucket for the build status
[01:46:12.714] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:12.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:127:0) after 1m0s
[01:47:12.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:127:0)
[01:47:12.796] Timer tick, asking Buildbucket for the build status
[01:47:12.912] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:12.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:128:0) after 1m0s
[01:48:12.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:128:0)
[01:48:12.994] Timer tick, asking Buildbucket for the build status
[01:48:13.419] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:13.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:129:0) after 1m0s
[01:49:13.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:129:0)
[01:49:13.410] Timer tick, asking Buildbucket for the build status
[01:49:13.498] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:13.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:130:0) after 1m0s
[01:50:13.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:130:0)
[01:50:13.524] Timer tick, asking Buildbucket for the build status
[01:50:13.628] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:13.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:131:0) after 1m0s
[01:51:13.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:131:0)
[01:51:13.736] Timer tick, asking Buildbucket for the build status
[01:51:13.836] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:13.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:132:0) after 1m0s
[01:52:13.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:132:0)
[01:52:13.940] Timer tick, asking Buildbucket for the build status
[01:52:14.039] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:14.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:133:0) after 1m0s
[01:53:14.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:133:0)
[01:53:14.103] Timer tick, asking Buildbucket for the build status
[01:53:14.165] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:14.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:134:0) after 1m0s
[01:54:14.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:134:0)
[01:54:14.364] Timer tick, asking Buildbucket for the build status
[01:54:14.435] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:14.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:135:0) after 1m0s
[01:55:14.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:135:0)
[01:55:14.692] Timer tick, asking Buildbucket for the build status
[01:55:14.786] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:14.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:136:0) after 1m0s
[01:56:15.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:136:0)
[01:56:15.074] Timer tick, asking Buildbucket for the build status
[01:56:15.173] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:15.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:137:0) after 1m0s
[01:57:15.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:137:0)
[01:57:15.346] Timer tick, asking Buildbucket for the build status
[01:57:15.460] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:15.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:138:0) after 1m0s
[01:58:15.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:138:0)
[01:58:15.574] Timer tick, asking Buildbucket for the build status
[01:58:15.859] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:15.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:139:0) after 1m0s
[01:59:16.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:139:0)
[01:59:16.022] Timer tick, asking Buildbucket for the build status
[01:59:16.302] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:16.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:140:0) after 1m0s
[02:00:16.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:140:0)
[02:00:16.355] Timer tick, asking Buildbucket for the build status
[02:00:16.417] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:16.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:141:0) after 1m0s
[02:01:17.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:141:0)
[02:01:17.358] Timer tick, asking Buildbucket for the build status
[02:01:17.443] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:17.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:142:0) after 1m0s
[02:02:17.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:142:0)
[02:02:17.689] Timer tick, asking Buildbucket for the build status
[02:02:17.790] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:17.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:143:0) after 1m0s
[02:03:18.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:143:0)
[02:03:18.019] Timer tick, asking Buildbucket for the build status
[02:03:18.367] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:18.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:144:0) after 1m0s
[02:04:18.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:144:0)
[02:04:18.488] Timer tick, asking Buildbucket for the build status
[02:04:18.743] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:18.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:145:0) after 1m0s
[02:05:19.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:145:0)
[02:05:19.064] Timer tick, asking Buildbucket for the build status
[02:05:19.163] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:19.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:146:0) after 1m0s
[02:06:19.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:146:0)
[02:06:19.311] Timer tick, asking Buildbucket for the build status
[02:06:19.777] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:19.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:147:0) after 1m0s
[02:07:19.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:147:0)
[02:07:19.869] Timer tick, asking Buildbucket for the build status
[02:07:19.965] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:19.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:148:0) after 1m0s
[02:08:19.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:148:0)
[02:08:19.991] Timer tick, asking Buildbucket for the build status
[02:08:21.000] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:21.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:149:0) after 1m0s
[02:09:21.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:149:0)
[02:09:21.158] Timer tick, asking Buildbucket for the build status
[02:09:21.281] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:21.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:150:0) after 1m0s
[02:10:21.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:150:0)
[02:10:21.321] Timer tick, asking Buildbucket for the build status
[02:10:21.439] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:21.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:151:0) after 1m0s
[02:11:21.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:151:0)
[02:11:21.539] Timer tick, asking Buildbucket for the build status
[02:11:21.606] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:21.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:152:0) after 1m0s
[02:12:21.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:152:0)
[02:12:21.713] Timer tick, asking Buildbucket for the build status
[02:12:21.877] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:21.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:153:0) after 1m0s
[02:13:21.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:153:0)
[02:13:21.899] Timer tick, asking Buildbucket for the build status
[02:13:22.090] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:22.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:154:0) after 1m0s
[02:14:22.126] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:154:0)
[02:14:22.126] Timer tick, asking Buildbucket for the build status
[02:14:22.281] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:22.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:155:0) after 1m0s
[02:15:22.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:155:0)
[02:15:22.331] Timer tick, asking Buildbucket for the build status
[02:15:22.407] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:22.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:156:0) after 1m0s
[02:16:24.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:156:0)
[02:16:24.388] Timer tick, asking Buildbucket for the build status
[02:16:24.452] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:24.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:157:0) after 1m0s
[02:17:24.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:157:0)
[02:17:24.487] Timer tick, asking Buildbucket for the build status
[02:17:24.585] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:24.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:158:0) after 1m0s
[02:18:24.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:158:0)
[02:18:24.837] Timer tick, asking Buildbucket for the build status
[02:18:25.023] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:25.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:159:0) after 1m0s
[02:19:25.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:159:0)
[02:19:25.047] Timer tick, asking Buildbucket for the build status
[02:19:25.290] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:25.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:160:0) after 1m0s
[02:20:25.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:160:0)
[02:20:25.387] Timer tick, asking Buildbucket for the build status
[02:20:25.516] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:25.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:161:0) after 1m0s
[02:21:25.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:161:0)
[02:21:25.587] Timer tick, asking Buildbucket for the build status
[02:21:25.693] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:25.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:162:0) after 1m0s
[02:22:25.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:162:0)
[02:22:25.757] Timer tick, asking Buildbucket for the build status
[02:22:26.017] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:26.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:163:0) after 1m0s
[02:23:26.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:163:0)
[02:23:26.057] Timer tick, asking Buildbucket for the build status
[02:23:26.160] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:26.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:164:0) after 1m0s
[02:24:26.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:164:0)
[02:24:26.183] Timer tick, asking Buildbucket for the build status
[02:24:26.278] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:26.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:165:0) after 1m0s
[02:25:26.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:165:0)
[02:25:26.305] Timer tick, asking Buildbucket for the build status
[02:25:26.363] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:26.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:166:0) after 1m0s
[02:26:26.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:166:0)
[02:26:26.481] Timer tick, asking Buildbucket for the build status
[02:26:26.539] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:26.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:167:0) after 1m0s
[02:27:26.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:167:0)
[02:27:26.812] Timer tick, asking Buildbucket for the build status
[02:27:27.227] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:27.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:168:0) after 1m0s
[02:28:27.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:168:0)
[02:28:27.382] Timer tick, asking Buildbucket for the build status
[02:28:27.476] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:27.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:169:0) after 1m0s
[02:29:27.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:169:0)
[02:29:27.796] Timer tick, asking Buildbucket for the build status
[02:29:27.891] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:27.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:170:0) after 1m0s
[02:30:27.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:170:0)
[02:30:27.948] Timer tick, asking Buildbucket for the build status
[02:30:28.093] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:28.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:171:0) after 1m0s
[02:31:28.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:171:0)
[02:31:28.193] Timer tick, asking Buildbucket for the build status
[02:31:28.521] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:28.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:172:0) after 1m0s
[02:32:28.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:172:0)
[02:32:28.585] Timer tick, asking Buildbucket for the build status
[02:32:28.880] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:28.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:173:0) after 1m0s
[02:33:28.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:173:0)
[02:33:28.933] Timer tick, asking Buildbucket for the build status
[02:33:28.993] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:28.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:174:0) after 1m0s
[02:34:29.100] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:174:0)
[02:34:29.100] Timer tick, asking Buildbucket for the build status
[02:34:29.172] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:29.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:175:0) after 1m0s
[02:35:29.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:175:0)
[02:35:29.206] Timer tick, asking Buildbucket for the build status
[02:35:29.380] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:29.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:176:0) after 1m0s
[02:36:29.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:176:0)
[02:36:29.403] Timer tick, asking Buildbucket for the build status
[02:36:29.459] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:29.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:177:0) after 1m0s
[02:37:29.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:177:0)
[02:37:29.769] Timer tick, asking Buildbucket for the build status
[02:37:29.893] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:29.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:178:0) after 1m0s
[02:38:29.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:178:0)
[02:38:29.958] Timer tick, asking Buildbucket for the build status
[02:38:30.025] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:30.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:179:0) after 1m0s
[02:39:30.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:179:0)
[02:39:30.160] Timer tick, asking Buildbucket for the build status
[02:39:30.242] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:30.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:180:0) after 1m0s
[02:40:30.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:180:0)
[02:40:30.248] Timer tick, asking Buildbucket for the build status
[02:40:30.387] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:30.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:181:0) after 1m0s
[02:41:30.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:181:0)
[02:41:30.497] Timer tick, asking Buildbucket for the build status
[02:41:30.710] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:30.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:182:0) after 1m0s
[02:42:30.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:182:0)
[02:42:30.776] Timer tick, asking Buildbucket for the build status
[02:42:30.873] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:30.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:183:0) after 1m0s
[02:43:31.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:183:0)
[02:43:31.038] Timer tick, asking Buildbucket for the build status
[02:43:32.913] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:32.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:184:0) after 1m0s
[02:44:32.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:184:0)
[02:44:32.970] Timer tick, asking Buildbucket for the build status
[02:44:33.135] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:33.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:185:0) after 1m0s
[02:45:33.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:185:0)
[02:45:33.203] Timer tick, asking Buildbucket for the build status
[02:45:33.270] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:33.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:186:0) after 1m0s
[02:46:33.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:186:0)
[02:46:33.346] Timer tick, asking Buildbucket for the build status
[02:46:33.585] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:33.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:187:0) after 1m0s
[02:47:33.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:187:0)
[02:47:33.613] Timer tick, asking Buildbucket for the build status
[02:47:33.771] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:33.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:188:0) after 1m0s
[02:48:33.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:188:0)
[02:48:33.847] Timer tick, asking Buildbucket for the build status
[02:48:33.939] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:33.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:189:0) after 1m0s
[02:49:33.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:189:0)
[02:49:33.971] Timer tick, asking Buildbucket for the build status
[02:49:34.145] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:34.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:190:0) after 1m0s
[02:50:34.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:190:0)
[02:50:34.176] Timer tick, asking Buildbucket for the build status
[02:50:34.249] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:34.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:191:0) after 1m0s
[02:51:34.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:191:0)
[02:51:34.326] Timer tick, asking Buildbucket for the build status
[02:51:34.443] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:192:0) after 1m0s
[02:52:34.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:192:0)
[02:52:34.441] Timer tick, asking Buildbucket for the build status
[02:52:36.318] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:36.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:193:0) after 1m0s
[02:53:36.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:193:0)
[02:53:36.319] Timer tick, asking Buildbucket for the build status
[02:53:36.405] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:36.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:194:0) after 1m0s
[02:54:36.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:194:0)
[02:54:36.431] Timer tick, asking Buildbucket for the build status
[02:54:36.487] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:36.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:195:0) after 1m0s
[02:55:36.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:195:0)
[02:55:36.680] Timer tick, asking Buildbucket for the build status
[02:55:36.740] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:36.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:196:0) after 1m0s
[02:56:36.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:196:0)
[02:56:36.814] Timer tick, asking Buildbucket for the build status
[02:56:37.391] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:37.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:197:0) after 1m0s
[02:57:37.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:197:0)
[02:57:37.494] Timer tick, asking Buildbucket for the build status
[02:57:37.632] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:37.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:198:0) after 1m0s
[02:58:37.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:198:0)
[02:58:37.697] Timer tick, asking Buildbucket for the build status
[02:58:37.752] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:37.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:199:0) after 1m0s
[02:59:37.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:199:0)
[02:59:37.782] Timer tick, asking Buildbucket for the build status
[02:59:37.871] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:37.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:200:0) after 1m0s
[03:00:37.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:200:0)
[03:00:37.932] Timer tick, asking Buildbucket for the build status
[03:00:39.482] Build 8867145920526859104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:39.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032575982942540736:201:0) after 1m0s
[03:00:44.273] Received PubSub notification, asking Buildbucket for the build status
[03:00:44.349] Build 8867145920526859104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:00:44.349] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1602039643475719",
    "created_by": "project:nacl",
    "created_ts": "1602027697975420",
    "id": "8867145920526859104",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@883ebe523b7b086567c2e70d260667959ea19f25\", \"title\": \"883ebe523b7b086567c2e70d260667959ea19f25\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/883ebe523b7b086567c2e70d260667959ea19f25\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@883ebe523b7b086567c2e70d260667959ea19f25\", \"title\": \"883ebe523b7b086567c2e70d260667959ea19f25\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/883ebe523b7b086567c2e70d260667959ea19f25\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-2iga\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7077, \"got_revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"883ebe523b7b086567c2e70d260667959ea19f25\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-2iga\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1602027753234280",
    "status": "COMPLETED",
    "status_changed_ts": "1602039643725631",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7077",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/883ebe523b7b086567c2e70d260667959ea19f25",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/883ebe523b7b086567c2e70d260667959ea19f25",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9032575982942540736",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8867145920526859104/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:4f191c41e28db310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1602039643735520",
    "url": "https://ci.chromium.org/b/8867145920526859104",
    "utcnow_ts": "1602039644340316"
  }
}
[03:00:44.349] Invocation finished in 3h19m8.062028108s with status SUCCEEDED