buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.infra.cron"
  builder: "Build From Tarball"
>

• publish_tarball/0 (5 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

{
  "version": "80.0.3987.67"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[01:55:43.678] New invocation is queued and will start shortly
[01:55:43.678] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:55:45.084] Starting the invocation (attempt 1)
[01:55:45.127] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:55:45.127] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:55:45.127] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9055850965023984672",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"80.0.3987.67\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"47b1b897-3cd2-4ff9-a5e0-ac960a720aa4\",\"title\":\"publish_tarball/0\"}]},\"version\":\"80.0.3987.67\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9055850965023984672",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[01:55:45.879] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1579830945463108",
    "id": "8890420902488373744",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.67\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"47b1b897-3cd2-4ff9-a5e0-ac960a720aa4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.67\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1579830945640944",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9055850965023984672",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8890420902488373744/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1579830945641075",
    "url": "https://ci.chromium.org/b/8890420902488373744",
    "utcnow_ts": "1579830945870065"
  }
}
[01:55:45.879] Task URL: https://ci.chromium.org/b/8890420902488373744
[01:55:45.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:2:0) after 1m0s
[01:56:45.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:2:0)
[01:56:45.945] Timer tick, asking Buildbucket for the build status
[01:56:46.117] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:46.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:3:0) after 1m0s
[01:57:46.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:3:0)
[01:57:46.234] Timer tick, asking Buildbucket for the build status
[01:57:46.697] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:46.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:4:0) after 1m0s
[01:58:46.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:4:0)
[01:58:46.747] Timer tick, asking Buildbucket for the build status
[01:58:48.417] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:48.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:5:0) after 1m0s
[01:59:48.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:5:0)
[01:59:48.440] Timer tick, asking Buildbucket for the build status
[01:59:49.313] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:49.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:6:0) after 1m0s
[02:00:49.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:6:0)
[02:00:49.416] Timer tick, asking Buildbucket for the build status
[02:00:51.155] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:51.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:7:0) after 1m0s
[02:01:51.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:7:0)
[02:01:51.185] Timer tick, asking Buildbucket for the build status
[02:01:51.356] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:51.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:8:0) after 1m0s
[02:02:51.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:8:0)
[02:02:51.437] Timer tick, asking Buildbucket for the build status
[02:02:51.558] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:51.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:9:0) after 1m0s
[02:03:51.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:9:0)
[02:03:51.600] Timer tick, asking Buildbucket for the build status
[02:03:51.717] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:51.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:10:0) after 1m0s
[02:04:51.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:10:0)
[02:04:51.717] Timer tick, asking Buildbucket for the build status
[02:04:51.867] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:51.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:11:0) after 1m0s
[02:05:51.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:11:0)
[02:05:51.975] Timer tick, asking Buildbucket for the build status
[02:05:52.112] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:52.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:12:0) after 1m0s
[02:06:52.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:12:0)
[02:06:52.180] Timer tick, asking Buildbucket for the build status
[02:06:52.290] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:52.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:13:0) after 1m0s
[02:07:52.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:13:0)
[02:07:52.437] Timer tick, asking Buildbucket for the build status
[02:07:52.608] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:52.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:14:0) after 1m0s
[02:08:52.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:14:0)
[02:08:52.820] Timer tick, asking Buildbucket for the build status
[02:08:52.929] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:52.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:15:0) after 1m0s
[02:09:53.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:15:0)
[02:09:53.157] Timer tick, asking Buildbucket for the build status
[02:09:53.339] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:53.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:16:0) after 1m0s
[02:10:53.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:16:0)
[02:10:53.365] Timer tick, asking Buildbucket for the build status
[02:10:53.499] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:53.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:17:0) after 1m0s
[02:11:53.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:17:0)
[02:11:53.568] Timer tick, asking Buildbucket for the build status
[02:11:53.655] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:53.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:18:0) after 1m0s
[02:12:53.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:18:0)
[02:12:53.694] Timer tick, asking Buildbucket for the build status
[02:12:53.820] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:53.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:19:0) after 1m0s
[02:13:53.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:19:0)
[02:13:53.834] Timer tick, asking Buildbucket for the build status
[02:13:54.023] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:54.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:20:0) after 1m0s
[02:14:54.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:20:0)
[02:14:54.049] Timer tick, asking Buildbucket for the build status
[02:14:54.348] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:54.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:21:0) after 1m0s
[02:15:54.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:21:0)
[02:15:54.379] Timer tick, asking Buildbucket for the build status
[02:15:54.486] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:54.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:22:0) after 1m0s
[02:16:54.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:22:0)
[02:16:54.551] Timer tick, asking Buildbucket for the build status
[02:16:54.862] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:54.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:23:0) after 1m0s
[02:17:55.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:23:0)
[02:17:55.047] Timer tick, asking Buildbucket for the build status
[02:17:55.257] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:55.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:24:0) after 1m0s
[02:18:55.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:24:0)
[02:18:55.315] Timer tick, asking Buildbucket for the build status
[02:18:55.485] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:55.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:25:0) after 1m0s
[02:19:55.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:25:0)
[02:19:55.657] Timer tick, asking Buildbucket for the build status
[02:19:55.996] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:55.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:26:0) after 1m0s
[02:20:56.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:26:0)
[02:20:56.019] Timer tick, asking Buildbucket for the build status
[02:20:56.182] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:56.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:27:0) after 1m0s
[02:21:56.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:27:0)
[02:21:56.450] Timer tick, asking Buildbucket for the build status
[02:21:56.862] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:56.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:28:0) after 1m0s
[02:22:57.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:28:0)
[02:22:57.018] Timer tick, asking Buildbucket for the build status
[02:22:57.140] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:57.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:29:0) after 1m0s
[02:23:57.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:29:0)
[02:23:57.240] Timer tick, asking Buildbucket for the build status
[02:23:57.392] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:57.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:30:0) after 1m0s
[02:24:57.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:30:0)
[02:24:57.711] Timer tick, asking Buildbucket for the build status
[02:24:57.837] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:57.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:31:0) after 1m0s
[02:25:58.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:31:0)
[02:25:58.249] Timer tick, asking Buildbucket for the build status
[02:25:58.391] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:58.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:32:0) after 1m0s
[02:26:58.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:32:0)
[02:26:58.456] Timer tick, asking Buildbucket for the build status
[02:26:58.572] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:58.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:33:0) after 1m0s
[02:27:58.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:33:0)
[02:27:58.728] Timer tick, asking Buildbucket for the build status
[02:27:58.959] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:58.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:34:0) after 1m0s
[02:28:59.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:34:0)
[02:28:59.140] Timer tick, asking Buildbucket for the build status
[02:28:59.411] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:59.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:35:0) after 1m0s
[02:29:59.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:35:0)
[02:29:59.723] Timer tick, asking Buildbucket for the build status
[02:29:59.903] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:59.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:36:0) after 1m0s
[02:31:00.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:36:0)
[02:31:00.099] Timer tick, asking Buildbucket for the build status
[02:31:00.277] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:00.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:37:0) after 1m0s
[02:32:00.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:37:0)
[02:32:00.455] Timer tick, asking Buildbucket for the build status
[02:32:00.716] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:00.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:38:0) after 1m0s
[02:33:00.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:38:0)
[02:33:00.961] Timer tick, asking Buildbucket for the build status
[02:33:01.322] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:01.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:39:0) after 1m0s
[02:34:01.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:39:0)
[02:34:01.549] Timer tick, asking Buildbucket for the build status
[02:34:01.991] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:01.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:40:0) after 1m0s
[02:35:02.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:40:0)
[02:35:02.179] Timer tick, asking Buildbucket for the build status
[02:35:02.403] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:02.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:41:0) after 1m0s
[02:36:02.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:41:0)
[02:36:02.509] Timer tick, asking Buildbucket for the build status
[02:36:02.729] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:02.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:42:0) after 1m0s
[02:37:02.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:42:0)
[02:37:02.876] Timer tick, asking Buildbucket for the build status
[02:37:03.017] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:03.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:43:0) after 1m0s
[02:38:03.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:43:0)
[02:38:03.028] Timer tick, asking Buildbucket for the build status
[02:38:03.226] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:03.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:44:0) after 1m0s
[02:39:03.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:44:0)
[02:39:03.372] Timer tick, asking Buildbucket for the build status
[02:39:03.661] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:03.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:45:0) after 1m0s
[02:40:03.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:45:0)
[02:40:03.794] Timer tick, asking Buildbucket for the build status
[02:40:04.387] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:04.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:46:0) after 1m0s
[02:41:04.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:46:0)
[02:41:04.445] Timer tick, asking Buildbucket for the build status
[02:41:04.833] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:04.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:47:0) after 1m0s
[02:42:04.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:47:0)
[02:42:04.892] Timer tick, asking Buildbucket for the build status
[02:42:05.119] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:05.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:48:0) after 1m0s
[02:43:05.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:48:0)
[02:43:05.150] Timer tick, asking Buildbucket for the build status
[02:43:05.613] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:05.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:49:0) after 1m0s
[02:44:05.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:49:0)
[02:44:05.706] Timer tick, asking Buildbucket for the build status
[02:44:05.906] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:05.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:50:0) after 1m0s
[02:45:05.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:50:0)
[02:45:05.938] Timer tick, asking Buildbucket for the build status
[02:45:06.146] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:06.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:51:0) after 1m0s
[02:46:06.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:51:0)
[02:46:06.188] Timer tick, asking Buildbucket for the build status
[02:46:06.431] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:06.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:52:0) after 1m0s
[02:47:06.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:52:0)
[02:47:06.476] Timer tick, asking Buildbucket for the build status
[02:47:06.837] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:06.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:53:0) after 1m0s
[02:48:06.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:53:0)
[02:48:06.932] Timer tick, asking Buildbucket for the build status
[02:48:07.122] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:07.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:54:0) after 1m0s
[02:49:07.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:54:0)
[02:49:07.221] Timer tick, asking Buildbucket for the build status
[02:49:07.604] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:07.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:55:0) after 1m0s
[02:50:07.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:55:0)
[02:50:07.600] Timer tick, asking Buildbucket for the build status
[02:50:07.756] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:07.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:56:0) after 1m0s
[02:51:07.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:56:0)
[02:51:07.845] Timer tick, asking Buildbucket for the build status
[02:51:07.957] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:07.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:57:0) after 1m0s
[02:52:08.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:57:0)
[02:52:08.001] Timer tick, asking Buildbucket for the build status
[02:52:08.161] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:08.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:58:0) after 1m0s
[02:53:08.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:58:0)
[02:53:08.194] Timer tick, asking Buildbucket for the build status
[02:53:08.306] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:08.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:59:0) after 1m0s
[02:54:08.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:59:0)
[02:54:08.494] Timer tick, asking Buildbucket for the build status
[02:54:09.104] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:09.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:60:0) after 1m0s
[02:55:09.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:60:0)
[02:55:09.163] Timer tick, asking Buildbucket for the build status
[02:55:09.759] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:09.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:61:0) after 1m0s
[02:56:09.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:61:0)
[02:56:09.828] Timer tick, asking Buildbucket for the build status
[02:56:10.200] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:10.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:62:0) after 1m0s
[02:57:10.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:62:0)
[02:57:10.261] Timer tick, asking Buildbucket for the build status
[02:57:10.393] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:10.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:63:0) after 1m0s
[02:58:10.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:63:0)
[02:58:10.459] Timer tick, asking Buildbucket for the build status
[02:58:10.693] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:10.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:64:0) after 1m0s
[02:59:10.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:64:0)
[02:59:10.802] Timer tick, asking Buildbucket for the build status
[02:59:10.934] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:10.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:65:0) after 1m0s
[03:00:10.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:65:0)
[03:00:10.975] Timer tick, asking Buildbucket for the build status
[03:00:11.093] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:11.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:66:0) after 1m0s
[03:01:11.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:66:0)
[03:01:11.125] Timer tick, asking Buildbucket for the build status
[03:01:11.271] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:11.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:67:0) after 1m0s
[03:02:11.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:67:0)
[03:02:11.381] Timer tick, asking Buildbucket for the build status
[03:02:12.019] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:12.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:68:0) after 1m0s
[03:03:12.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:68:0)
[03:03:12.046] Timer tick, asking Buildbucket for the build status
[03:03:12.566] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:12.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:69:0) after 1m0s
[03:04:12.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:69:0)
[03:04:12.660] Timer tick, asking Buildbucket for the build status
[03:04:12.888] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:12.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:70:0) after 1m0s
[03:05:12.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:70:0)
[03:05:12.946] Timer tick, asking Buildbucket for the build status
[03:05:15.680] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:15.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:71:0) after 1m0s
[03:06:15.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:71:0)
[03:06:15.709] Timer tick, asking Buildbucket for the build status
[03:06:16.127] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:16.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:72:0) after 1m0s
[03:07:16.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:72:0)
[03:07:16.249] Timer tick, asking Buildbucket for the build status
[03:07:16.501] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:16.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:73:0) after 1m0s
[03:08:16.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:73:0)
[03:08:16.585] Timer tick, asking Buildbucket for the build status
[03:08:16.948] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:16.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:74:0) after 1m0s
[03:09:17.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:74:0)
[03:09:17.006] Timer tick, asking Buildbucket for the build status
[03:09:17.413] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:17.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:75:0) after 1m0s
[03:10:17.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:75:0)
[03:10:17.446] Timer tick, asking Buildbucket for the build status
[03:10:17.559] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:17.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:76:0) after 1m0s
[03:11:17.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:76:0)
[03:11:17.684] Timer tick, asking Buildbucket for the build status
[03:11:17.862] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:17.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:77:0) after 1m0s
[03:12:17.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:77:0)
[03:12:17.894] Timer tick, asking Buildbucket for the build status
[03:12:18.068] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:18.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:78:0) after 1m0s
[03:13:18.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:78:0)
[03:13:18.158] Timer tick, asking Buildbucket for the build status
[03:13:18.771] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:18.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:79:0) after 1m0s
[03:14:18.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:79:0)
[03:14:18.972] Timer tick, asking Buildbucket for the build status
[03:14:19.167] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:19.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:80:0) after 1m0s
[03:15:19.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:80:0)
[03:15:19.233] Timer tick, asking Buildbucket for the build status
[03:15:19.459] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:19.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:81:0) after 1m0s
[03:16:19.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:81:0)
[03:16:19.529] Timer tick, asking Buildbucket for the build status
[03:16:19.798] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:19.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:82:0) after 1m0s
[03:17:19.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:82:0)
[03:17:19.864] Timer tick, asking Buildbucket for the build status
[03:17:19.984] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:19.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:83:0) after 1m0s
[03:18:20.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:83:0)
[03:18:20.007] Timer tick, asking Buildbucket for the build status
[03:18:20.114] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:20.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:84:0) after 1m0s
[03:19:20.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:84:0)
[03:19:20.399] Timer tick, asking Buildbucket for the build status
[03:19:20.648] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:20.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:85:0) after 1m0s
[03:20:20.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:85:0)
[03:20:20.773] Timer tick, asking Buildbucket for the build status
[03:20:20.915] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:20.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:86:0) after 1m0s
[03:21:20.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:86:0)
[03:21:20.947] Timer tick, asking Buildbucket for the build status
[03:21:21.099] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:21.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:87:0) after 1m0s
[03:22:21.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:87:0)
[03:22:21.129] Timer tick, asking Buildbucket for the build status
[03:22:21.280] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:21.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:88:0) after 1m0s
[03:23:21.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:88:0)
[03:23:21.307] Timer tick, asking Buildbucket for the build status
[03:23:21.429] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:21.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:89:0) after 1m0s
[03:24:21.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:89:0)
[03:24:21.410] Timer tick, asking Buildbucket for the build status
[03:24:21.658] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:21.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:90:0) after 1m0s
[03:25:21.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:90:0)
[03:25:21.718] Timer tick, asking Buildbucket for the build status
[03:25:21.843] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:21.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:91:0) after 1m0s
[03:26:21.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:91:0)
[03:26:21.879] Timer tick, asking Buildbucket for the build status
[03:26:22.156] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:22.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:92:0) after 1m0s
[03:27:22.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:92:0)
[03:27:22.197] Timer tick, asking Buildbucket for the build status
[03:27:22.785] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:22.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:93:0) after 1m0s
[03:28:22.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:93:0)
[03:28:22.823] Timer tick, asking Buildbucket for the build status
[03:28:23.200] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:23.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:94:0) after 1m0s
[03:29:23.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:94:0)
[03:29:23.272] Timer tick, asking Buildbucket for the build status
[03:29:23.563] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:23.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:95:0) after 1m0s
[03:30:23.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:95:0)
[03:30:23.553] Timer tick, asking Buildbucket for the build status
[03:30:23.739] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:23.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:96:0) after 1m0s
[03:31:23.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:96:0)
[03:31:23.763] Timer tick, asking Buildbucket for the build status
[03:31:25.212] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:25.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:97:0) after 1m0s
[03:32:25.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:97:0)
[03:32:25.333] Timer tick, asking Buildbucket for the build status
[03:32:25.653] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:25.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:98:0) after 1m0s
[03:33:25.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:98:0)
[03:33:25.790] Timer tick, asking Buildbucket for the build status
[03:33:25.895] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:25.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:99:0) after 1m0s
[03:34:25.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:99:0)
[03:34:25.909] Timer tick, asking Buildbucket for the build status
[03:34:25.999] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:25.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:100:0) after 1m0s
[03:35:26.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:100:0)
[03:35:26.077] Timer tick, asking Buildbucket for the build status
[03:35:26.204] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:26.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:101:0) after 1m0s
[03:36:26.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:101:0)
[03:36:26.246] Timer tick, asking Buildbucket for the build status
[03:36:26.527] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:26.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:102:0) after 1m0s
[03:37:26.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:102:0)
[03:37:26.551] Timer tick, asking Buildbucket for the build status
[03:37:26.687] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:26.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:103:0) after 1m0s
[03:38:26.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:103:0)
[03:38:26.717] Timer tick, asking Buildbucket for the build status
[03:38:26.865] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:26.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:104:0) after 1m0s
[03:39:26.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:104:0)
[03:39:26.970] Timer tick, asking Buildbucket for the build status
[03:39:28.808] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:28.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:105:0) after 1m0s
[03:40:28.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:105:0)
[03:40:28.841] Timer tick, asking Buildbucket for the build status
[03:40:28.968] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:28.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:106:0) after 1m0s
[03:41:29.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:106:0)
[03:41:29.038] Timer tick, asking Buildbucket for the build status
[03:41:29.201] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:29.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:107:0) after 1m0s
[03:42:29.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:107:0)
[03:42:29.373] Timer tick, asking Buildbucket for the build status
[03:42:29.900] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:29.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:108:0) after 1m0s
[03:43:29.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:108:0)
[03:43:29.914] Timer tick, asking Buildbucket for the build status
[03:43:30.302] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:30.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:109:0) after 1m0s
[03:44:30.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:109:0)
[03:44:30.471] Timer tick, asking Buildbucket for the build status
[03:44:30.608] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:30.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:110:0) after 1m0s
[03:45:30.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:110:0)
[03:45:30.685] Timer tick, asking Buildbucket for the build status
[03:45:30.776] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:30.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:111:0) after 1m0s
[03:46:30.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:111:0)
[03:46:30.763] Timer tick, asking Buildbucket for the build status
[03:46:30.991] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:30.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:112:0) after 1m0s
[03:47:31.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:112:0)
[03:47:31.143] Timer tick, asking Buildbucket for the build status
[03:47:31.560] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:31.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:113:0) after 1m0s
[03:48:31.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:113:0)
[03:48:31.582] Timer tick, asking Buildbucket for the build status
[03:48:31.726] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:31.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:114:0) after 1m0s
[03:49:31.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:114:0)
[03:49:31.938] Timer tick, asking Buildbucket for the build status
[03:49:32.165] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:32.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:115:0) after 1m0s
[03:50:32.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:115:0)
[03:50:32.240] Timer tick, asking Buildbucket for the build status
[03:50:32.403] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:32.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:116:0) after 1m0s
[03:51:32.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:116:0)
[03:51:32.428] Timer tick, asking Buildbucket for the build status
[03:51:32.776] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:32.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:117:0) after 1m0s
[03:52:32.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:117:0)
[03:52:32.865] Timer tick, asking Buildbucket for the build status
[03:52:33.015] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:33.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:118:0) after 1m0s
[03:53:33.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:118:0)
[03:53:33.098] Timer tick, asking Buildbucket for the build status
[03:53:33.330] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:33.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:119:0) after 1m0s
[03:54:33.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:119:0)
[03:54:33.395] Timer tick, asking Buildbucket for the build status
[03:54:33.526] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:33.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:120:0) after 1m0s
[03:55:33.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:120:0)
[03:55:33.601] Timer tick, asking Buildbucket for the build status
[03:55:33.710] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:33.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:121:0) after 1m0s
[03:56:33.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:121:0)
[03:56:33.830] Timer tick, asking Buildbucket for the build status
[03:56:33.950] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:33.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:122:0) after 1m0s
[03:57:34.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:122:0)
[03:57:34.167] Timer tick, asking Buildbucket for the build status
[03:57:34.373] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:34.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:123:0) after 1m0s
[03:58:34.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:123:0)
[03:58:34.654] Timer tick, asking Buildbucket for the build status
[03:58:35.096] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:35.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:124:0) after 1m0s
[03:59:35.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:124:0)
[03:59:35.109] Timer tick, asking Buildbucket for the build status
[03:59:35.279] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:35.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:125:0) after 1m0s
[04:00:35.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:125:0)
[04:00:35.374] Timer tick, asking Buildbucket for the build status
[04:00:35.552] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:35.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:126:0) after 1m0s
[04:01:35.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:126:0)
[04:01:35.670] Timer tick, asking Buildbucket for the build status
[04:01:35.820] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:35.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:127:0) after 1m0s
[04:02:35.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:127:0)
[04:02:35.935] Timer tick, asking Buildbucket for the build status
[04:02:36.062] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:36.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:128:0) after 1m0s
[04:03:36.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:128:0)
[04:03:36.185] Timer tick, asking Buildbucket for the build status
[04:03:36.392] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:36.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:129:0) after 1m0s
[04:04:36.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:129:0)
[04:04:36.492] Timer tick, asking Buildbucket for the build status
[04:04:36.874] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:36.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:130:0) after 1m0s
[04:05:37.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:130:0)
[04:05:37.029] Timer tick, asking Buildbucket for the build status
[04:05:37.173] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:37.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:131:0) after 1m0s
[04:06:37.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:131:0)
[04:06:37.243] Timer tick, asking Buildbucket for the build status
[04:06:37.403] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:37.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:132:0) after 1m0s
[04:07:37.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:132:0)
[04:07:37.528] Timer tick, asking Buildbucket for the build status
[04:07:37.673] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:37.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:133:0) after 1m0s
[04:08:37.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:133:0)
[04:08:37.741] Timer tick, asking Buildbucket for the build status
[04:08:37.925] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:37.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:134:0) after 1m0s
[04:09:37.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:134:0)
[04:09:37.959] Timer tick, asking Buildbucket for the build status
[04:09:38.075] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:38.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:135:0) after 1m0s
[04:10:38.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:135:0)
[04:10:38.069] Timer tick, asking Buildbucket for the build status
[04:10:38.448] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:38.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:136:0) after 1m0s
[04:11:38.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:136:0)
[04:11:38.479] Timer tick, asking Buildbucket for the build status
[04:11:38.594] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:38.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:137:0) after 1m0s
[04:12:38.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:137:0)
[04:12:38.662] Timer tick, asking Buildbucket for the build status
[04:12:38.812] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:38.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:138:0) after 1m0s
[04:13:38.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:138:0)
[04:13:38.835] Timer tick, asking Buildbucket for the build status
[04:13:38.972] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:38.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:139:0) after 1m0s
[04:14:39.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:139:0)
[04:14:39.008] Timer tick, asking Buildbucket for the build status
[04:14:39.422] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:39.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:140:0) after 1m0s
[04:15:39.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:140:0)
[04:15:39.461] Timer tick, asking Buildbucket for the build status
[04:15:39.584] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:39.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:141:0) after 1m0s
[04:16:39.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:141:0)
[04:16:39.613] Timer tick, asking Buildbucket for the build status
[04:16:39.729] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:39.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:142:0) after 1m0s
[04:17:39.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:142:0)
[04:17:39.868] Timer tick, asking Buildbucket for the build status
[04:17:40.049] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:40.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:143:0) after 1m0s
[04:18:40.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:143:0)
[04:18:40.237] Timer tick, asking Buildbucket for the build status
[04:18:40.534] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:40.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:144:0) after 1m0s
[04:19:40.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:144:0)
[04:19:40.544] Timer tick, asking Buildbucket for the build status
[04:19:40.819] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:40.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:145:0) after 1m0s
[04:20:40.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:145:0)
[04:20:40.881] Timer tick, asking Buildbucket for the build status
[04:20:41.002] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:41.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:146:0) after 1m0s
[04:21:41.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:146:0)
[04:21:41.065] Timer tick, asking Buildbucket for the build status
[04:21:41.618] Build 8890420902488373744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:41.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:147:0) after 1m0s
[04:21:47.749] Received PubSub notification, asking Buildbucket for the build status
[04:21:47.951] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:41.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:147:0)
[04:22:41.653] Timer tick, asking Buildbucket for the build status
[04:22:42.262] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:42.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:149:0) after 1m0s
[04:23:42.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:149:0)
[04:23:42.306] Timer tick, asking Buildbucket for the build status
[04:23:42.660] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:42.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:150:0) after 1m0s
[04:24:42.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:150:0)
[04:24:42.727] Timer tick, asking Buildbucket for the build status
[04:24:43.152] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:43.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:151:0) after 1m0s
[04:25:43.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:151:0)
[04:25:43.202] Timer tick, asking Buildbucket for the build status
[04:25:43.758] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:43.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:152:0) after 1m0s
[04:26:43.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:152:0)
[04:26:43.789] Timer tick, asking Buildbucket for the build status
[04:26:43.942] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:43.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:153:0) after 1m0s
[04:27:44.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:153:0)
[04:27:44.027] Timer tick, asking Buildbucket for the build status
[04:27:44.390] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:44.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:154:0) after 1m0s
[04:28:44.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:154:0)
[04:28:44.435] Timer tick, asking Buildbucket for the build status
[04:28:44.538] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:44.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:155:0) after 1m0s
[04:29:44.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:155:0)
[04:29:44.524] Timer tick, asking Buildbucket for the build status
[04:29:44.799] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:44.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:156:0) after 1m0s
[04:30:44.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:156:0)
[04:30:44.825] Timer tick, asking Buildbucket for the build status
[04:30:44.984] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:44.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:157:0) after 1m0s
[04:31:45.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:157:0)
[04:31:45.005] Timer tick, asking Buildbucket for the build status
[04:31:45.206] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:45.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:158:0) after 1m0s
[04:32:45.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:158:0)
[04:32:45.355] Timer tick, asking Buildbucket for the build status
[04:32:45.683] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:45.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:159:0) after 1m0s
[04:33:45.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:159:0)
[04:33:45.709] Timer tick, asking Buildbucket for the build status
[04:33:46.189] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:46.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:160:0) after 1m0s
[04:34:46.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:160:0)
[04:34:46.267] Timer tick, asking Buildbucket for the build status
[04:34:46.392] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:46.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:161:0) after 1m0s
[04:35:46.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:161:0)
[04:35:46.393] Timer tick, asking Buildbucket for the build status
[04:35:46.713] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:46.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:162:0) after 1m0s
[04:36:46.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:162:0)
[04:36:46.747] Timer tick, asking Buildbucket for the build status
[04:36:46.901] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:46.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:163:0) after 1m0s
[04:37:46.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:163:0)
[04:37:46.940] Timer tick, asking Buildbucket for the build status
[04:37:47.102] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:47.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:164:0) after 1m0s
[04:38:47.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:164:0)
[04:38:47.145] Timer tick, asking Buildbucket for the build status
[04:38:47.274] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:47.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:165:0) after 1m0s
[04:39:47.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:165:0)
[04:39:47.308] Timer tick, asking Buildbucket for the build status
[04:39:47.448] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:47.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:166:0) after 1m0s
[04:40:47.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:166:0)
[04:40:47.509] Timer tick, asking Buildbucket for the build status
[04:40:47.656] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:47.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:167:0) after 1m0s
[04:41:47.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:167:0)
[04:41:47.682] Timer tick, asking Buildbucket for the build status
[04:41:47.788] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:47.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:168:0) after 1m0s
[04:42:47.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:168:0)
[04:42:47.875] Timer tick, asking Buildbucket for the build status
[04:42:47.981] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:47.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:169:0) after 1m0s
[04:43:48.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:169:0)
[04:43:48.056] Timer tick, asking Buildbucket for the build status
[04:43:48.349] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:48.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:170:0) after 1m0s
[04:44:48.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:170:0)
[04:44:48.452] Timer tick, asking Buildbucket for the build status
[04:44:48.845] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:48.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:171:0) after 1m0s
[04:45:48.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:171:0)
[04:45:48.878] Timer tick, asking Buildbucket for the build status
[04:45:49.071] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:49.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:172:0) after 1m0s
[04:46:49.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:172:0)
[04:46:49.114] Timer tick, asking Buildbucket for the build status
[04:46:49.256] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:49.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:173:0) after 1m0s
[04:47:49.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:173:0)
[04:47:49.331] Timer tick, asking Buildbucket for the build status
[04:47:49.443] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:49.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:174:0) after 1m0s
[04:48:49.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:174:0)
[04:48:49.540] Timer tick, asking Buildbucket for the build status
[04:48:49.656] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:49.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:175:0) after 1m0s
[04:49:49.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:175:0)
[04:49:49.686] Timer tick, asking Buildbucket for the build status
[04:49:49.790] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:49.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:176:0) after 1m0s
[04:50:49.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:176:0)
[04:50:49.854] Timer tick, asking Buildbucket for the build status
[04:50:49.980] Build 8890420902488373744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:49.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9055850965023984672:177:0) after 1m0s
[04:51:17.113] Received PubSub notification, asking Buildbucket for the build status
[04:51:17.185] Build 8890420902488373744: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:51:17.185] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1579841476149773",
    "created_by": "project:infra",
    "created_ts": "1579830945463108",
    "failure_reason": "BUILD_FAILURE",
    "id": "8890420902488373744",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.67\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"47b1b897-3cd2-4ff9-a5e0-ac960a720aa4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.67\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('Bootstrap gn.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$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\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.67\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"47b1b897-3cd2-4ff9-a5e0-ac960a720aa4\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-j08y\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"80.0.3987.67\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-j08y\"], \"image\": [\"chrome-xenial-20011900-be725824aad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Bootstrap gn.') (retcode: 1)\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1579839706456679",
    "status": "COMPLETED",
    "status_changed_ts": "1579839706915560",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9055850965023984672",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8890420902488373744/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:49ee14b0cd355410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1579841476455839",
    "url": "https://ci.chromium.org/b/8890420902488373744",
    "utcnow_ts": "1579841477177957"
  }
}
[04:51:17.185] Invocation finished in 2h55m33.523175111s with status FAILED