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

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

{
  "version": "79.0.3916.3"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[23:16:27.768] New invocation is queued and will start shortly
[23:16:27.768] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:16:28.911] Starting the invocation (attempt 1)
[23:16:28.965] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:28.965] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:28.965] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9067366799856711744",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"79.0.3916.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"413d79ab-c37a-437a-915f-141a38c3d36b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"79.0.3916.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9067366799856711744",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[23:16:30.712] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1568848590077875",
    "id": "8901936736769520528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3916.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"413d79ab-c37a-437a-915f-141a38c3d36b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3916.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1568848590267163",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9067366799856711744",
      "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/8901936736769520528/+/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": "1568848590267299",
    "url": "https://ci.chromium.org/b/8901936736769520528",
    "utcnow_ts": "1568848590696057"
  }
}
[23:16:30.712] Task URL: https://ci.chromium.org/b/8901936736769520528
[23:16:30.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:2:0) after 1m0s
[23:17:30.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:2:0)
[23:17:30.802] Timer tick, asking Buildbucket for the build status
[23:17:31.541] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:31.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:3:0) after 1m0s
[23:18:31.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:3:0)
[23:18:31.522] Timer tick, asking Buildbucket for the build status
[23:18:32.221] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:32.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:4:0) after 1m0s
[23:19:32.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:4:0)
[23:19:32.266] Timer tick, asking Buildbucket for the build status
[23:19:32.398] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:32.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:5:0) after 1m0s
[23:20:32.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:5:0)
[23:20:32.523] Timer tick, asking Buildbucket for the build status
[23:20:32.850] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:32.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:6:0) after 1m0s
[23:21:32.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:6:0)
[23:21:32.873] Timer tick, asking Buildbucket for the build status
[23:21:33.345] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:33.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:7:0) after 1m0s
[23:22:33.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:7:0)
[23:22:33.425] Timer tick, asking Buildbucket for the build status
[23:22:33.858] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:33.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:8:0) after 1m0s
[23:23:33.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:8:0)
[23:23:33.900] Timer tick, asking Buildbucket for the build status
[23:23:34.093] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:34.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:9:0) after 1m0s
[23:24:34.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:9:0)
[23:24:34.164] Timer tick, asking Buildbucket for the build status
[23:24:34.280] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:34.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:10:0) after 1m0s
[23:25:34.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:10:0)
[23:25:34.302] Timer tick, asking Buildbucket for the build status
[23:25:34.990] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:34.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:11:0) after 1m0s
[23:26:35.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:11:0)
[23:26:35.064] Timer tick, asking Buildbucket for the build status
[23:26:36.162] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:36.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:12:0) after 1m0s
[23:27:36.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:12:0)
[23:27:36.185] Timer tick, asking Buildbucket for the build status
[23:27:36.313] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:36.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:13:0) after 1m0s
[23:28:36.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:13:0)
[23:28:36.336] Timer tick, asking Buildbucket for the build status
[23:28:36.815] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:36.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:14:0) after 1m0s
[23:29:36.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:14:0)
[23:29:36.850] Timer tick, asking Buildbucket for the build status
[23:29:37.435] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:37.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:15:0) after 1m0s
[23:30:37.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:15:0)
[23:30:37.500] Timer tick, asking Buildbucket for the build status
[23:30:37.609] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:37.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:16:0) after 1m0s
[23:31:37.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:16:0)
[23:31:37.656] Timer tick, asking Buildbucket for the build status
[23:31:37.817] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:37.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:17:0) after 1m0s
[23:32:37.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:17:0)
[23:32:37.855] Timer tick, asking Buildbucket for the build status
[23:32:37.972] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:37.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:18:0) after 1m0s
[23:33:37.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:18:0)
[23:33:37.989] Timer tick, asking Buildbucket for the build status
[23:33:38.237] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:38.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:19:0) after 1m0s
[23:34:38.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:19:0)
[23:34:38.274] Timer tick, asking Buildbucket for the build status
[23:34:38.584] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:38.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:20:0) after 1m0s
[23:35:38.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:20:0)
[23:35:38.619] Timer tick, asking Buildbucket for the build status
[23:35:38.720] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:38.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:21:0) after 1m0s
[23:36:38.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:21:0)
[23:36:38.977] Timer tick, asking Buildbucket for the build status
[23:36:39.219] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:39.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:22:0) after 1m0s
[23:37:39.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:22:0)
[23:37:39.244] Timer tick, asking Buildbucket for the build status
[23:37:39.431] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:39.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:23:0) after 1m0s
[23:38:39.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:23:0)
[23:38:39.547] Timer tick, asking Buildbucket for the build status
[23:38:39.670] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:39.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:24:0) after 1m0s
[23:39:39.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:24:0)
[23:39:39.744] Timer tick, asking Buildbucket for the build status
[23:39:39.897] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:39.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:25:0) after 1m0s
[23:40:40.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:25:0)
[23:40:40.016] Timer tick, asking Buildbucket for the build status
[23:40:40.191] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:40.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:26:0) after 1m0s
[23:41:40.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:26:0)
[23:41:40.231] Timer tick, asking Buildbucket for the build status
[23:41:40.487] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:40.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:27:0) after 1m0s
[23:42:40.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:27:0)
[23:42:40.483] Timer tick, asking Buildbucket for the build status
[23:42:41.228] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:41.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:28:0) after 1m0s
[23:43:41.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:28:0)
[23:43:41.276] Timer tick, asking Buildbucket for the build status
[23:43:41.656] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:41.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:29:0) after 1m0s
[23:44:41.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:29:0)
[23:44:41.721] Timer tick, asking Buildbucket for the build status
[23:44:41.853] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:41.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:30:0) after 1m0s
[23:45:41.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:30:0)
[23:45:41.916] Timer tick, asking Buildbucket for the build status
[23:45:42.860] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:42.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:31:0) after 1m0s
[23:46:42.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:31:0)
[23:46:42.872] Timer tick, asking Buildbucket for the build status
[23:46:43.042] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:43.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:32:0) after 1m0s
[23:47:43.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:32:0)
[23:47:43.070] Timer tick, asking Buildbucket for the build status
[23:47:43.214] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:43.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:33:0) after 1m0s
[23:48:43.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:33:0)
[23:48:43.274] Timer tick, asking Buildbucket for the build status
[23:48:44.079] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:44.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:34:0) after 1m0s
[23:49:44.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:34:0)
[23:49:44.145] Timer tick, asking Buildbucket for the build status
[23:49:44.255] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:44.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:35:0) after 1m0s
[23:50:44.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:35:0)
[23:50:44.370] Timer tick, asking Buildbucket for the build status
[23:50:44.817] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:44.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:36:0) after 1m0s
[23:51:44.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:36:0)
[23:51:44.915] Timer tick, asking Buildbucket for the build status
[23:51:45.051] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:45.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:37:0) after 1m0s
[23:52:45.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:37:0)
[23:52:45.067] Timer tick, asking Buildbucket for the build status
[23:52:45.421] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:45.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:38:0) after 1m0s
[23:53:45.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:38:0)
[23:53:45.553] Timer tick, asking Buildbucket for the build status
[23:53:45.934] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:45.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:39:0) after 1m0s
[23:54:45.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:39:0)
[23:54:45.986] Timer tick, asking Buildbucket for the build status
[23:54:46.178] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:46.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:40:0) after 1m0s
[23:55:46.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:40:0)
[23:55:46.310] Timer tick, asking Buildbucket for the build status
[23:55:46.704] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:46.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:41:0) after 1m0s
[23:56:46.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:41:0)
[23:56:46.752] Timer tick, asking Buildbucket for the build status
[23:56:46.891] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:46.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:42:0) after 1m0s
[23:57:46.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:42:0)
[23:57:46.981] Timer tick, asking Buildbucket for the build status
[23:57:47.151] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:47.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:43:0) after 1m0s
[23:58:47.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:43:0)
[23:58:47.174] Timer tick, asking Buildbucket for the build status
[23:58:47.423] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:47.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:44:0) after 1m0s
[23:59:47.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:44:0)
[23:59:47.568] Timer tick, asking Buildbucket for the build status
[23:59:47.832] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:47.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:45:0) after 1m0s
[00:00:47.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:45:0)
[00:00:47.874] Timer tick, asking Buildbucket for the build status
[00:00:48.119] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:48.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:46:0) after 1m0s
[00:01:48.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:46:0)
[00:01:48.231] Timer tick, asking Buildbucket for the build status
[00:01:48.490] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:48.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:47:0) after 1m0s
[00:02:48.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:47:0)
[00:02:48.528] Timer tick, asking Buildbucket for the build status
[00:02:48.702] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:48.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:48:0) after 1m0s
[00:03:48.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:48:0)
[00:03:48.973] Timer tick, asking Buildbucket for the build status
[00:03:49.089] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:49.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:49:0) after 1m0s
[00:04:49.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:49:0)
[00:04:49.194] Timer tick, asking Buildbucket for the build status
[00:04:49.610] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:49.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:50:0) after 1m0s
[00:05:49.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:50:0)
[00:05:49.949] Timer tick, asking Buildbucket for the build status
[00:05:50.332] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:50.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:51:0) after 1m0s
[00:06:50.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:51:0)
[00:06:50.463] Timer tick, asking Buildbucket for the build status
[00:06:50.591] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:50.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:52:0) after 1m0s
[00:07:50.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:52:0)
[00:07:50.753] Timer tick, asking Buildbucket for the build status
[00:07:51.128] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:51.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:53:0) after 1m0s
[00:08:51.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:53:0)
[00:08:51.167] Timer tick, asking Buildbucket for the build status
[00:08:51.334] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:51.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:54:0) after 1m0s
[00:09:51.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:54:0)
[00:09:51.488] Timer tick, asking Buildbucket for the build status
[00:09:51.919] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:51.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:55:0) after 1m0s
[00:10:52.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:55:0)
[00:10:52.049] Timer tick, asking Buildbucket for the build status
[00:10:53.279] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:53.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:56:0) after 1m0s
[00:11:53.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:56:0)
[00:11:53.452] Timer tick, asking Buildbucket for the build status
[00:11:53.604] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:53.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:57:0) after 1m0s
[00:12:53.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:57:0)
[00:12:53.645] Timer tick, asking Buildbucket for the build status
[00:12:53.958] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:53.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:58:0) after 1m0s
[00:13:53.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:58:0)
[00:13:53.982] Timer tick, asking Buildbucket for the build status
[00:13:54.269] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:54.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:59:0) after 1m0s
[00:14:54.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:59:0)
[00:14:54.382] Timer tick, asking Buildbucket for the build status
[00:14:54.601] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:54.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:60:0) after 1m0s
[00:15:54.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:60:0)
[00:15:54.632] Timer tick, asking Buildbucket for the build status
[00:15:54.909] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:54.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:61:0) after 1m0s
[00:16:54.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:61:0)
[00:16:54.949] Timer tick, asking Buildbucket for the build status
[00:16:57.954] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:57.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:62:0) after 1m0s
[00:17:58.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:62:0)
[00:17:58.066] Timer tick, asking Buildbucket for the build status
[00:17:58.491] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:58.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:63:0) after 1m0s
[00:18:59.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:63:0)
[00:18:59.262] Timer tick, asking Buildbucket for the build status
[00:18:59.708] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:59.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:64:0) after 1m0s
[00:19:59.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:64:0)
[00:19:59.785] Timer tick, asking Buildbucket for the build status
[00:20:00.298] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:00.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:65:0) after 1m0s
[00:21:00.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:65:0)
[00:21:00.365] Timer tick, asking Buildbucket for the build status
[00:21:00.634] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:00.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:66:0) after 1m0s
[00:22:00.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:66:0)
[00:22:00.691] Timer tick, asking Buildbucket for the build status
[00:22:00.889] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:00.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:67:0) after 1m0s
[00:23:00.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:67:0)
[00:23:00.928] Timer tick, asking Buildbucket for the build status
[00:23:01.068] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:01.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:68:0) after 1m0s
[00:24:01.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:68:0)
[00:24:01.127] Timer tick, asking Buildbucket for the build status
[00:24:01.408] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:01.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:69:0) after 1m0s
[00:25:01.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:69:0)
[00:25:01.426] Timer tick, asking Buildbucket for the build status
[00:25:01.613] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:01.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:70:0) after 1m0s
[00:26:01.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:70:0)
[00:26:01.683] Timer tick, asking Buildbucket for the build status
[00:26:01.937] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:01.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:71:0) after 1m0s
[00:27:01.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:71:0)
[00:27:01.962] Timer tick, asking Buildbucket for the build status
[00:27:02.307] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:02.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:72:0) after 1m0s
[00:28:02.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:72:0)
[00:28:02.527] Timer tick, asking Buildbucket for the build status
[00:28:02.754] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:02.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:73:0) after 1m0s
[00:29:02.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:73:0)
[00:29:02.929] Timer tick, asking Buildbucket for the build status
[00:29:03.227] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:03.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:74:0) after 1m0s
[00:30:03.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:74:0)
[00:30:03.322] Timer tick, asking Buildbucket for the build status
[00:30:05.018] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:05.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:75:0) after 1m0s
[00:31:05.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:75:0)
[00:31:05.144] Timer tick, asking Buildbucket for the build status
[00:31:05.371] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:05.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:76:0) after 1m0s
[00:32:05.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:76:0)
[00:32:05.478] Timer tick, asking Buildbucket for the build status
[00:32:05.676] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:05.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:77:0) after 1m0s
[00:33:05.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:77:0)
[00:33:05.712] Timer tick, asking Buildbucket for the build status
[00:33:05.927] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:05.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:78:0) after 1m0s
[00:34:06.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:78:0)
[00:34:06.017] Timer tick, asking Buildbucket for the build status
[00:34:06.536] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:06.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:79:0) after 1m0s
[00:35:06.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:79:0)
[00:35:06.724] Timer tick, asking Buildbucket for the build status
[00:35:07.218] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:07.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:80:0) after 1m0s
[00:36:07.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:80:0)
[00:36:07.320] Timer tick, asking Buildbucket for the build status
[00:36:07.481] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:07.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:81:0) after 1m0s
[00:37:07.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:81:0)
[00:37:07.524] Timer tick, asking Buildbucket for the build status
[00:37:07.735] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:07.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:82:0) after 1m0s
[00:38:07.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:82:0)
[00:38:07.759] Timer tick, asking Buildbucket for the build status
[00:38:08.326] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:08.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:83:0) after 1m0s
[00:39:08.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:83:0)
[00:39:08.419] Timer tick, asking Buildbucket for the build status
[00:39:08.574] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:08.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:84:0) after 1m0s
[00:40:08.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:84:0)
[00:40:08.653] Timer tick, asking Buildbucket for the build status
[00:40:09.287] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:09.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:85:0) after 1m0s
[00:41:09.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:85:0)
[00:41:09.385] Timer tick, asking Buildbucket for the build status
[00:41:09.677] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:09.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:86:0) after 1m0s
[00:42:09.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:86:0)
[00:42:09.721] Timer tick, asking Buildbucket for the build status
[00:42:09.855] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:09.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:87:0) after 1m0s
[00:43:09.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:87:0)
[00:43:09.944] Timer tick, asking Buildbucket for the build status
[00:43:10.052] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:10.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:88:0) after 1m0s
[00:44:10.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:88:0)
[00:44:10.136] Timer tick, asking Buildbucket for the build status
[00:44:10.489] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:10.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:89:0) after 1m0s
[00:45:10.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:89:0)
[00:45:10.517] Timer tick, asking Buildbucket for the build status
[00:45:10.720] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:10.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:90:0) after 1m0s
[00:46:10.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:90:0)
[00:46:10.803] Timer tick, asking Buildbucket for the build status
[00:46:11.012] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:11.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:91:0) after 1m0s
[00:47:11.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:91:0)
[00:47:11.177] Timer tick, asking Buildbucket for the build status
[00:47:11.355] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:11.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:92:0) after 1m0s
[00:48:11.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:92:0)
[00:48:11.426] Timer tick, asking Buildbucket for the build status
[00:48:11.900] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:11.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:93:0) after 1m0s
[00:49:12.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:93:0)
[00:49:12.095] Timer tick, asking Buildbucket for the build status
[00:49:12.702] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:12.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:94:0) after 1m0s
[00:50:12.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:94:0)
[00:50:12.772] Timer tick, asking Buildbucket for the build status
[00:50:13.294] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:13.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:95:0) after 1m0s
[00:51:13.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:95:0)
[00:51:13.324] Timer tick, asking Buildbucket for the build status
[00:51:13.841] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:13.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:96:0) after 1m0s
[00:52:13.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:96:0)
[00:52:13.878] Timer tick, asking Buildbucket for the build status
[00:52:14.050] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:14.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:97:0) after 1m0s
[00:53:14.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:97:0)
[00:53:14.077] Timer tick, asking Buildbucket for the build status
[00:53:14.300] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:14.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:98:0) after 1m0s
[00:54:14.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:98:0)
[00:54:14.289] Timer tick, asking Buildbucket for the build status
[00:54:14.432] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:14.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:99:0) after 1m0s
[00:55:14.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:99:0)
[00:55:14.464] Timer tick, asking Buildbucket for the build status
[00:55:14.677] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:14.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:100:0) after 1m0s
[00:56:14.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:100:0)
[00:56:14.769] Timer tick, asking Buildbucket for the build status
[00:56:15.094] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:15.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:101:0) after 1m0s
[00:57:15.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:101:0)
[00:57:15.171] Timer tick, asking Buildbucket for the build status
[00:57:15.451] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:15.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:102:0) after 1m0s
[00:58:16.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:102:0)
[00:58:16.082] Timer tick, asking Buildbucket for the build status
[00:58:16.269] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:16.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:103:0) after 1m0s
[00:59:16.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:103:0)
[00:59:16.476] Timer tick, asking Buildbucket for the build status
[00:59:16.871] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:16.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:104:0) after 1m0s
[01:00:17.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:104:0)
[01:00:17.194] Timer tick, asking Buildbucket for the build status
[01:00:17.362] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:17.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:105:0) after 1m0s
[01:01:17.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:105:0)
[01:01:17.691] Timer tick, asking Buildbucket for the build status
[01:01:17.832] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:17.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:106:0) after 1m0s
[01:02:18.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:106:0)
[01:02:18.098] Timer tick, asking Buildbucket for the build status
[01:02:18.738] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:18.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:107:0) after 1m0s
[01:03:18.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:107:0)
[01:03:18.874] Timer tick, asking Buildbucket for the build status
[01:03:19.291] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:19.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:108:0) after 1m0s
[01:04:19.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:108:0)
[01:04:19.321] Timer tick, asking Buildbucket for the build status
[01:04:19.755] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:19.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:109:0) after 1m0s
[01:05:19.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:109:0)
[01:05:19.834] Timer tick, asking Buildbucket for the build status
[01:05:19.987] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:19.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:110:0) after 1m0s
[01:06:20.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:110:0)
[01:06:20.114] Timer tick, asking Buildbucket for the build status
[01:06:20.289] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:20.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:111:0) after 1m0s
[01:07:20.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:111:0)
[01:07:20.439] Timer tick, asking Buildbucket for the build status
[01:07:21.133] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:21.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:112:0) after 1m0s
[01:08:21.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:112:0)
[01:08:21.305] Timer tick, asking Buildbucket for the build status
[01:08:21.476] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:21.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:113:0) after 1m0s
[01:09:21.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:113:0)
[01:09:21.705] Timer tick, asking Buildbucket for the build status
[01:09:22.187] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:22.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:114:0) after 1m0s
[01:10:22.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:114:0)
[01:10:22.262] Timer tick, asking Buildbucket for the build status
[01:10:22.926] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:22.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:115:0) after 1m0s
[01:11:22.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:115:0)
[01:11:22.971] Timer tick, asking Buildbucket for the build status
[01:11:23.403] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:23.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:116:0) after 1m0s
[01:12:23.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:116:0)
[01:12:23.501] Timer tick, asking Buildbucket for the build status
[01:12:23.935] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:23.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:117:0) after 1m0s
[01:13:24.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:117:0)
[01:13:24.014] Timer tick, asking Buildbucket for the build status
[01:13:24.379] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:24.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:118:0) after 1m0s
[01:14:24.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:118:0)
[01:14:24.397] Timer tick, asking Buildbucket for the build status
[01:14:24.687] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:24.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:119:0) after 1m0s
[01:15:24.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:119:0)
[01:15:24.723] Timer tick, asking Buildbucket for the build status
[01:15:24.884] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:24.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:120:0) after 1m0s
[01:16:24.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:120:0)
[01:16:24.933] Timer tick, asking Buildbucket for the build status
[01:16:25.231] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:25.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:121:0) after 1m0s
[01:17:25.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:121:0)
[01:17:25.302] Timer tick, asking Buildbucket for the build status
[01:17:25.452] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:25.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:122:0) after 1m0s
[01:18:25.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:122:0)
[01:18:25.509] Timer tick, asking Buildbucket for the build status
[01:18:25.778] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:25.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:123:0) after 1m0s
[01:19:25.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:123:0)
[01:19:25.840] Timer tick, asking Buildbucket for the build status
[01:19:26.112] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:26.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:124:0) after 1m0s
[01:20:26.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:124:0)
[01:20:26.284] Timer tick, asking Buildbucket for the build status
[01:20:26.434] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:26.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:125:0) after 1m0s
[01:21:26.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:125:0)
[01:21:26.514] Timer tick, asking Buildbucket for the build status
[01:21:26.673] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:26.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:126:0) after 1m0s
[01:22:26.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:126:0)
[01:22:26.914] Timer tick, asking Buildbucket for the build status
[01:22:28.319] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:28.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:127:0) after 1m0s
[01:23:28.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:127:0)
[01:23:28.363] Timer tick, asking Buildbucket for the build status
[01:23:29.870] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:29.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:128:0) after 1m0s
[01:24:30.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:128:0)
[01:24:30.127] Timer tick, asking Buildbucket for the build status
[01:24:30.614] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:30.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:129:0) after 1m0s
[01:25:30.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:129:0)
[01:25:30.672] Timer tick, asking Buildbucket for the build status
[01:25:32.216] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:32.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:130:0) after 1m0s
[01:26:32.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:130:0)
[01:26:32.292] Timer tick, asking Buildbucket for the build status
[01:26:32.587] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:32.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:131:0) after 1m0s
[01:27:32.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:131:0)
[01:27:32.631] Timer tick, asking Buildbucket for the build status
[01:27:33.255] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:33.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:132:0) after 1m0s
[01:28:33.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:132:0)
[01:28:33.309] Timer tick, asking Buildbucket for the build status
[01:28:33.487] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:33.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:133:0) after 1m0s
[01:29:33.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:133:0)
[01:29:33.543] Timer tick, asking Buildbucket for the build status
[01:29:33.977] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:33.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:134:0) after 1m0s
[01:30:34.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:134:0)
[01:30:34.051] Timer tick, asking Buildbucket for the build status
[01:30:34.261] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:34.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:135:0) after 1m0s
[01:31:34.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:135:0)
[01:31:34.341] Timer tick, asking Buildbucket for the build status
[01:31:34.508] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:34.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:136:0) after 1m0s
[01:32:34.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:136:0)
[01:32:34.579] Timer tick, asking Buildbucket for the build status
[01:32:36.147] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:36.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:137:0) after 1m0s
[01:33:36.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:137:0)
[01:33:36.314] Timer tick, asking Buildbucket for the build status
[01:33:36.473] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:36.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:138:0) after 1m0s
[01:34:36.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:138:0)
[01:34:36.556] Timer tick, asking Buildbucket for the build status
[01:34:37.106] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:37.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:139:0) after 1m0s
[01:35:37.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:139:0)
[01:35:37.141] Timer tick, asking Buildbucket for the build status
[01:35:37.382] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:37.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:140:0) after 1m0s
[01:36:37.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:140:0)
[01:36:37.548] Timer tick, asking Buildbucket for the build status
[01:36:37.705] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:37.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:141:0) after 1m0s
[01:37:37.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:141:0)
[01:37:37.729] Timer tick, asking Buildbucket for the build status
[01:37:38.275] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:38.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:142:0) after 1m0s
[01:38:38.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:142:0)
[01:38:38.266] Timer tick, asking Buildbucket for the build status
[01:38:38.792] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:38.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:143:0) after 1m0s
[01:39:38.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:143:0)
[01:39:38.886] Timer tick, asking Buildbucket for the build status
[01:39:39.132] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:39.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:144:0) after 1m0s
[01:40:39.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:144:0)
[01:40:39.223] Timer tick, asking Buildbucket for the build status
[01:40:39.599] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:39.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:145:0) after 1m0s
[01:41:39.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:145:0)
[01:41:39.734] Timer tick, asking Buildbucket for the build status
[01:41:40.054] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:40.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:146:0) after 1m0s
[01:42:40.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:146:0)
[01:42:40.159] Timer tick, asking Buildbucket for the build status
[01:42:40.546] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:40.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:147:0) after 1m0s
[01:43:40.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:147:0)
[01:43:40.993] Timer tick, asking Buildbucket for the build status
[01:43:41.288] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:41.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:148:0) after 1m0s
[01:44:41.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:148:0)
[01:44:41.534] Timer tick, asking Buildbucket for the build status
[01:44:41.758] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:41.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:149:0) after 1m0s
[01:45:42.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:149:0)
[01:45:42.064] Timer tick, asking Buildbucket for the build status
[01:45:42.723] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:42.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:150:0) after 1m0s
[01:46:42.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:150:0)
[01:46:42.737] Timer tick, asking Buildbucket for the build status
[01:46:46.252] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:46.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:151:0) after 1m0s
[01:47:46.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:151:0)
[01:47:46.287] Timer tick, asking Buildbucket for the build status
[01:47:46.666] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:46.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:152:0) after 1m0s
[01:48:46.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:152:0)
[01:48:46.702] Timer tick, asking Buildbucket for the build status
[01:48:47.215] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:47.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:153:0) after 1m0s
[01:49:47.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:153:0)
[01:49:47.318] Timer tick, asking Buildbucket for the build status
[01:49:47.516] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:47.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:154:0) after 1m0s
[01:50:47.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:154:0)
[01:50:47.669] Timer tick, asking Buildbucket for the build status
[01:50:47.985] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:47.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:155:0) after 1m0s
[01:51:48.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:155:0)
[01:51:48.052] Timer tick, asking Buildbucket for the build status
[01:51:48.176] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:48.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:156:0) after 1m0s
[01:52:48.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:156:0)
[01:52:48.337] Timer tick, asking Buildbucket for the build status
[01:52:48.499] Build 8901936736769520528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:48.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:157:0) after 1m0s
[01:53:22.716] Received PubSub notification, asking Buildbucket for the build status
[01:53:22.897] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:48.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:157:0)
[01:53:48.662] Timer tick, asking Buildbucket for the build status
[01:53:48.877] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:48.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:159:0) after 1m0s
[01:54:48.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:159:0)
[01:54:48.942] Timer tick, asking Buildbucket for the build status
[01:54:49.159] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:49.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:160:0) after 1m0s
[01:55:49.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:160:0)
[01:55:49.186] Timer tick, asking Buildbucket for the build status
[01:55:49.356] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:49.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:161:0) after 1m0s
[01:56:49.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:161:0)
[01:56:49.388] Timer tick, asking Buildbucket for the build status
[01:56:49.606] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:49.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:162:0) after 1m0s
[01:57:49.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:162:0)
[01:57:49.777] Timer tick, asking Buildbucket for the build status
[01:57:50.439] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:50.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:163:0) after 1m0s
[01:58:50.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:163:0)
[01:58:50.469] Timer tick, asking Buildbucket for the build status
[01:58:50.607] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:50.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:164:0) after 1m0s
[01:59:50.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:164:0)
[01:59:50.666] Timer tick, asking Buildbucket for the build status
[01:59:51.815] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:51.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:165:0) after 1m0s
[02:00:51.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:165:0)
[02:00:51.818] Timer tick, asking Buildbucket for the build status
[02:00:52.216] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:52.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:166:0) after 1m0s
[02:01:52.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:166:0)
[02:01:52.255] Timer tick, asking Buildbucket for the build status
[02:01:52.529] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:52.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:167:0) after 1m0s
[02:02:52.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:167:0)
[02:02:52.614] Timer tick, asking Buildbucket for the build status
[02:02:52.741] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:52.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:168:0) after 1m0s
[02:03:52.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:168:0)
[02:03:52.797] Timer tick, asking Buildbucket for the build status
[02:03:53.026] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:53.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:169:0) after 1m0s
[02:04:53.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:169:0)
[02:04:53.089] Timer tick, asking Buildbucket for the build status
[02:04:53.528] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:53.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:170:0) after 1m0s
[02:05:53.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:170:0)
[02:05:53.618] Timer tick, asking Buildbucket for the build status
[02:05:53.751] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:53.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:171:0) after 1m0s
[02:06:53.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:171:0)
[02:06:53.848] Timer tick, asking Buildbucket for the build status
[02:06:54.005] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:54.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:172:0) after 1m0s
[02:07:54.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:172:0)
[02:07:54.079] Timer tick, asking Buildbucket for the build status
[02:07:54.516] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:54.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:173:0) after 1m0s
[02:08:54.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:173:0)
[02:08:54.545] Timer tick, asking Buildbucket for the build status
[02:08:54.915] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:54.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:174:0) after 1m0s
[02:09:55.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:174:0)
[02:09:55.013] Timer tick, asking Buildbucket for the build status
[02:09:55.123] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:55.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:175:0) after 1m0s
[02:10:55.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:175:0)
[02:10:55.154] Timer tick, asking Buildbucket for the build status
[02:10:56.094] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:56.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:176:0) after 1m0s
[02:11:56.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:176:0)
[02:11:56.141] Timer tick, asking Buildbucket for the build status
[02:11:56.442] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:56.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:177:0) after 1m0s
[02:12:56.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:177:0)
[02:12:56.498] Timer tick, asking Buildbucket for the build status
[02:12:56.712] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:56.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:178:0) after 1m0s
[02:13:56.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:178:0)
[02:13:56.745] Timer tick, asking Buildbucket for the build status
[02:13:57.391] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:57.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:179:0) after 1m0s
[02:14:57.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:179:0)
[02:14:57.443] Timer tick, asking Buildbucket for the build status
[02:14:58.318] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:58.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:180:0) after 1m0s
[02:15:58.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:180:0)
[02:15:58.335] Timer tick, asking Buildbucket for the build status
[02:15:59.024] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:59.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:181:0) after 1m0s
[02:16:59.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:181:0)
[02:16:59.048] Timer tick, asking Buildbucket for the build status
[02:16:59.273] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:59.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:182:0) after 1m0s
[02:17:59.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:182:0)
[02:17:59.306] Timer tick, asking Buildbucket for the build status
[02:17:59.518] Build 8901936736769520528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:59.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9067366799856711744:183:0) after 1m0s
[02:18:20.209] Received PubSub notification, asking Buildbucket for the build status
[02:18:21.816] Build 8901936736769520528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:18:21.816] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1568859499749174",
    "created_by": "project:infra",
    "created_ts": "1568848590077875",
    "failure_reason": "BUILD_FAILURE",
    "id": "8901936736769520528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3916.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"413d79ab-c37a-437a-915f-141a38c3d36b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3916.3\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('Download nodejs.') (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\": \"79.0.3916.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"413d79ab-c37a-437a-915f-141a38c3d36b\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-wmw7\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"79.0.3916.3\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-wmw7\"], \"image\": [\"chrome-xenial-19090600-ed917d8233f\"], \"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\": [\"4443-3a52fcb\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Download nodejs.') (retcode: 1)\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1568858000620662",
    "status": "COMPLETED",
    "status_changed_ts": "1568858001713214",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9067366799856711744",
      "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/8901936736769520528/+/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:475f7b4363dcae10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1568859499819455",
    "url": "https://ci.chromium.org/b/8901936736769520528",
    "utcnow_ts": "1568859501802334"
  }
}
[02:18:21.816] Invocation finished in 3h1m54.067231311s with status FAILED