[01:47:26.785] New invocation is queued and will start shortly
[01:47:26.785] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:47:27.885] Starting the invocation (attempt 1)
[01:47:27.952] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:47:27.952] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:47:27.952] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9029397171868511792",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4319.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c53b9f92-95f9-41d4-b5d4-c62952fb5107\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4319.2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9029397171868511792",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[01:47:28.657] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1605059248084117",
"id": "8863967109839157856",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4319.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c53b9f92-95f9-41d4-b5d4-c62952fb5107\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4319.2\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605059248372063",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9029397171868511792",
"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/8863967109839157856/+/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": "1605059248372172",
"url": "https://ci.chromium.org/b/8863967109839157856",
"utcnow_ts": "1605059248649008"
}
}
[01:47:28.657] Task URL: https://ci.chromium.org/b/8863967109839157856
[01:47:28.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:2:0) after 1m0s
[01:48:28.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:2:0)
[01:48:28.700] Timer tick, asking Buildbucket for the build status
[01:48:29.038] Build 8863967109839157856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:29.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:3:0) after 1m0s
[01:49:29.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:3:0)
[01:49:29.098] Timer tick, asking Buildbucket for the build status
[01:49:29.181] Build 8863967109839157856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:29.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:4:0) after 1m0s
[01:50:29.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:4:0)
[01:50:29.207] Timer tick, asking Buildbucket for the build status
[01:50:29.309] Build 8863967109839157856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:29.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:5:0) after 1m0s
[01:51:29.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:5:0)
[01:51:29.454] Timer tick, asking Buildbucket for the build status
[01:51:29.658] Build 8863967109839157856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:29.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:6:0) after 1m0s
[01:52:29.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:6:0)
[01:52:29.650] Timer tick, asking Buildbucket for the build status
[01:52:29.776] Build 8863967109839157856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:29.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:7:0) after 1m0s
[01:53:05.309] Received PubSub notification, asking Buildbucket for the build status
[01:53:05.393] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:29.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:7:0)
[01:53:29.838] Timer tick, asking Buildbucket for the build status
[01:53:30.026] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:30.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:9:0) after 1m0s
[01:54:30.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:9:0)
[01:54:30.088] Timer tick, asking Buildbucket for the build status
[01:54:30.593] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:30.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:10:0) after 1m0s
[01:55:30.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:10:0)
[01:55:30.679] Timer tick, asking Buildbucket for the build status
[01:55:30.812] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:30.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:11:0) after 1m0s
[01:56:30.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:11:0)
[01:56:30.833] Timer tick, asking Buildbucket for the build status
[01:56:31.225] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:31.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:12:0) after 1m0s
[01:57:31.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:12:0)
[01:57:31.254] Timer tick, asking Buildbucket for the build status
[01:57:31.326] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:31.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:13:0) after 1m0s
[01:58:31.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:13:0)
[01:58:31.303] Timer tick, asking Buildbucket for the build status
[01:58:32.968] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:32.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:14:0) after 1m0s
[01:59:32.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:14:0)
[01:59:32.993] Timer tick, asking Buildbucket for the build status
[01:59:33.275] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:33.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:15:0) after 1m0s
[02:00:33.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:15:0)
[02:00:33.319] Timer tick, asking Buildbucket for the build status
[02:00:36.811] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:36.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:16:0) after 1m0s
[02:01:36.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:16:0)
[02:01:36.803] Timer tick, asking Buildbucket for the build status
[02:01:36.884] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:36.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:17:0) after 1m0s
[02:02:36.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:17:0)
[02:02:36.934] Timer tick, asking Buildbucket for the build status
[02:02:37.142] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:37.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:18:0) after 1m0s
[02:03:37.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:18:0)
[02:03:37.178] Timer tick, asking Buildbucket for the build status
[02:03:37.320] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:37.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:19:0) after 1m0s
[02:04:37.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:19:0)
[02:04:37.340] Timer tick, asking Buildbucket for the build status
[02:04:37.434] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:37.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:20:0) after 1m0s
[02:05:37.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:20:0)
[02:05:37.492] Timer tick, asking Buildbucket for the build status
[02:05:37.593] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:37.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:21:0) after 1m0s
[02:06:37.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:21:0)
[02:06:37.615] Timer tick, asking Buildbucket for the build status
[02:06:37.927] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:37.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:22:0) after 1m0s
[02:07:37.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:22:0)
[02:07:37.952] Timer tick, asking Buildbucket for the build status
[02:07:38.965] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:38.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:23:0) after 1m0s
[02:08:38.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:23:0)
[02:08:38.990] Timer tick, asking Buildbucket for the build status
[02:08:39.215] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:39.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:24:0) after 1m0s
[02:09:39.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:24:0)
[02:09:39.288] Timer tick, asking Buildbucket for the build status
[02:09:39.875] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:39.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:25:0) after 1m0s
[02:10:39.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:25:0)
[02:10:39.959] Timer tick, asking Buildbucket for the build status
[02:10:40.047] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:40.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:26:0) after 1m0s
[02:11:40.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:26:0)
[02:11:40.065] Timer tick, asking Buildbucket for the build status
[02:11:40.200] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:40.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:27:0) after 1m0s
[02:12:40.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:27:0)
[02:12:40.222] Timer tick, asking Buildbucket for the build status
[02:12:40.352] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:40.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:28:0) after 1m0s
[02:13:40.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:28:0)
[02:13:40.379] Timer tick, asking Buildbucket for the build status
[02:13:40.463] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:40.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:29:0) after 1m0s
[02:14:40.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:29:0)
[02:14:40.485] Timer tick, asking Buildbucket for the build status
[02:14:40.565] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:40.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:30:0) after 1m0s
[02:15:40.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:30:0)
[02:15:40.588] Timer tick, asking Buildbucket for the build status
[02:15:40.759] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:40.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:31:0) after 1m0s
[02:16:40.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:31:0)
[02:16:40.780] Timer tick, asking Buildbucket for the build status
[02:16:40.857] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:40.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:32:0) after 1m0s
[02:17:40.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:32:0)
[02:17:40.947] Timer tick, asking Buildbucket for the build status
[02:17:41.385] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:41.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:33:0) after 1m0s
[02:18:41.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:33:0)
[02:18:41.418] Timer tick, asking Buildbucket for the build status
[02:18:41.515] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:41.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:34:0) after 1m0s
[02:19:41.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:34:0)
[02:19:41.595] Timer tick, asking Buildbucket for the build status
[02:19:41.844] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:41.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:35:0) after 1m0s
[02:20:41.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:35:0)
[02:20:41.863] Timer tick, asking Buildbucket for the build status
[02:20:41.968] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:41.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:36:0) after 1m0s
[02:21:42.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:36:0)
[02:21:42.042] Timer tick, asking Buildbucket for the build status
[02:21:42.125] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:42.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:37:0) after 1m0s
[02:22:42.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:37:0)
[02:22:42.113] Timer tick, asking Buildbucket for the build status
[02:22:42.225] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:42.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:38:0) after 1m0s
[02:23:42.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:38:0)
[02:23:42.252] Timer tick, asking Buildbucket for the build status
[02:23:42.566] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:42.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:39:0) after 1m0s
[02:24:42.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:39:0)
[02:24:42.570] Timer tick, asking Buildbucket for the build status
[02:24:42.705] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:42.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:40:0) after 1m0s
[02:25:42.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:40:0)
[02:25:42.733] Timer tick, asking Buildbucket for the build status
[02:25:42.833] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:42.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:41:0) after 1m0s
[02:26:42.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:41:0)
[02:26:42.890] Timer tick, asking Buildbucket for the build status
[02:26:43.039] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:43.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:42:0) after 1m0s
[02:27:43.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:42:0)
[02:27:43.074] Timer tick, asking Buildbucket for the build status
[02:27:43.256] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:43.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:43:0) after 1m0s
[02:28:43.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:43:0)
[02:28:43.311] Timer tick, asking Buildbucket for the build status
[02:28:43.518] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:43.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:44:0) after 1m0s
[02:29:43.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:44:0)
[02:29:43.517] Timer tick, asking Buildbucket for the build status
[02:29:43.679] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:43.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:45:0) after 1m0s
[02:30:43.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:45:0)
[02:30:43.708] Timer tick, asking Buildbucket for the build status
[02:30:43.875] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:43.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:46:0) after 1m0s
[02:31:43.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:46:0)
[02:31:43.918] Timer tick, asking Buildbucket for the build status
[02:31:43.990] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:43.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:47:0) after 1m0s
[02:32:44.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:47:0)
[02:32:44.009] Timer tick, asking Buildbucket for the build status
[02:32:44.125] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:48:0) after 1m0s
[02:33:44.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:48:0)
[02:33:44.189] Timer tick, asking Buildbucket for the build status
[02:33:44.300] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:44.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:49:0) after 1m0s
[02:34:44.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:49:0)
[02:34:44.307] Timer tick, asking Buildbucket for the build status
[02:34:44.577] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:44.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:50:0) after 1m0s
[02:35:44.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:50:0)
[02:35:44.627] Timer tick, asking Buildbucket for the build status
[02:35:44.769] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:44.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:51:0) after 1m0s
[02:36:44.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:51:0)
[02:36:44.776] Timer tick, asking Buildbucket for the build status
[02:36:44.855] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:44.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:52:0) after 1m0s
[02:37:44.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:52:0)
[02:37:44.902] Timer tick, asking Buildbucket for the build status
[02:37:46.420] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:46.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:53:0) after 1m0s
[02:38:46.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:53:0)
[02:38:46.440] Timer tick, asking Buildbucket for the build status
[02:38:46.526] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:46.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:54:0) after 1m0s
[02:39:46.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:54:0)
[02:39:46.567] Timer tick, asking Buildbucket for the build status
[02:39:47.231] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:47.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:55:0) after 1m0s
[02:40:47.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:55:0)
[02:40:47.346] Timer tick, asking Buildbucket for the build status
[02:40:47.427] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:47.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:56:0) after 1m0s
[02:41:47.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:56:0)
[02:41:47.501] Timer tick, asking Buildbucket for the build status
[02:41:47.712] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:47.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:57:0) after 1m0s
[02:42:47.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:57:0)
[02:42:47.734] Timer tick, asking Buildbucket for the build status
[02:42:47.856] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:47.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:58:0) after 1m0s
[02:43:47.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:58:0)
[02:43:47.953] Timer tick, asking Buildbucket for the build status
[02:43:48.044] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:48.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:59:0) after 1m0s
[02:44:48.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:59:0)
[02:44:48.042] Timer tick, asking Buildbucket for the build status
[02:44:48.149] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:48.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:60:0) after 1m0s
[02:45:48.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:60:0)
[02:45:48.188] Timer tick, asking Buildbucket for the build status
[02:45:48.309] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:48.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:61:0) after 1m0s
[02:46:48.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:61:0)
[02:46:48.379] Timer tick, asking Buildbucket for the build status
[02:46:48.464] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:48.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:62:0) after 1m0s
[02:47:48.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:62:0)
[02:47:48.979] Timer tick, asking Buildbucket for the build status
[02:47:49.463] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:49.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:63:0) after 1m0s
[02:48:49.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:63:0)
[02:48:49.563] Timer tick, asking Buildbucket for the build status
[02:48:49.848] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:49.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:64:0) after 1m0s
[02:49:49.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:64:0)
[02:49:49.832] Timer tick, asking Buildbucket for the build status
[02:49:50.199] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:50.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:65:0) after 1m0s
[02:50:50.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:65:0)
[02:50:50.235] Timer tick, asking Buildbucket for the build status
[02:50:50.350] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:50.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:66:0) after 1m0s
[02:51:50.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:66:0)
[02:51:50.363] Timer tick, asking Buildbucket for the build status
[02:51:51.044] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:51.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:67:0) after 1m0s
[02:52:51.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:67:0)
[02:52:51.083] Timer tick, asking Buildbucket for the build status
[02:52:51.199] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:51.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:68:0) after 1m0s
[02:53:51.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:68:0)
[02:53:51.251] Timer tick, asking Buildbucket for the build status
[02:53:51.712] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:51.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:69:0) after 1m0s
[02:54:51.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:69:0)
[02:54:51.737] Timer tick, asking Buildbucket for the build status
[02:54:52.853] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:52.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:70:0) after 1m0s
[02:55:52.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:70:0)
[02:55:52.928] Timer tick, asking Buildbucket for the build status
[02:55:53.100] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:53.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:71:0) after 1m0s
[02:56:53.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:71:0)
[02:56:53.235] Timer tick, asking Buildbucket for the build status
[02:56:53.306] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:72:0) after 1m0s
[02:57:53.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:72:0)
[02:57:53.416] Timer tick, asking Buildbucket for the build status
[02:57:53.706] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:73:0) after 1m0s
[02:58:53.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:73:0)
[02:58:53.762] Timer tick, asking Buildbucket for the build status
[02:58:53.887] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:74:0) after 1m0s
[02:59:53.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:74:0)
[02:59:53.990] Timer tick, asking Buildbucket for the build status
[02:59:54.072] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:54.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:75:0) after 1m0s
[03:00:54.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:75:0)
[03:00:54.110] Timer tick, asking Buildbucket for the build status
[03:00:54.305] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:76:0) after 1m0s
[03:01:54.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:76:0)
[03:01:54.621] Timer tick, asking Buildbucket for the build status
[03:01:54.968] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:54.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:77:0) after 1m0s
[03:02:55.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:77:0)
[03:02:55.000] Timer tick, asking Buildbucket for the build status
[03:02:55.170] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:78:0) after 1m0s
[03:03:55.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:78:0)
[03:03:55.219] Timer tick, asking Buildbucket for the build status
[03:03:55.347] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:55.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:79:0) after 1m0s
[03:04:55.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:79:0)
[03:04:55.337] Timer tick, asking Buildbucket for the build status
[03:04:55.762] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:55.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:80:0) after 1m0s
[03:05:55.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:80:0)
[03:05:55.844] Timer tick, asking Buildbucket for the build status
[03:05:55.930] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:55.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:81:0) after 1m0s
[03:06:55.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:81:0)
[03:06:55.970] Timer tick, asking Buildbucket for the build status
[03:06:56.128] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:56.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:82:0) after 1m0s
[03:07:56.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:82:0)
[03:07:56.136] Timer tick, asking Buildbucket for the build status
[03:07:56.285] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:56.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:83:0) after 1m0s
[03:08:56.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:83:0)
[03:08:56.348] Timer tick, asking Buildbucket for the build status
[03:08:56.431] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:56.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:84:0) after 1m0s
[03:09:56.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:84:0)
[03:09:56.483] Timer tick, asking Buildbucket for the build status
[03:09:56.598] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:56.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:85:0) after 1m0s
[03:10:56.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:85:0)
[03:10:56.843] Timer tick, asking Buildbucket for the build status
[03:10:57.034] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:57.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:86:0) after 1m0s
[03:11:57.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:86:0)
[03:11:57.144] Timer tick, asking Buildbucket for the build status
[03:11:58.634] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:58.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:87:0) after 1m0s
[03:12:58.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:87:0)
[03:12:58.828] Timer tick, asking Buildbucket for the build status
[03:12:58.946] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:58.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:88:0) after 1m0s
[03:13:59.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:88:0)
[03:13:59.016] Timer tick, asking Buildbucket for the build status
[03:14:00.264] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:00.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:89:0) after 1m0s
[03:15:00.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:89:0)
[03:15:00.292] Timer tick, asking Buildbucket for the build status
[03:15:00.438] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:00.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:90:0) after 1m0s
[03:16:00.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:90:0)
[03:16:00.573] Timer tick, asking Buildbucket for the build status
[03:16:02.322] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:02.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:91:0) after 1m0s
[03:17:02.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:91:0)
[03:17:02.386] Timer tick, asking Buildbucket for the build status
[03:17:02.485] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:02.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:92:0) after 1m0s
[03:18:04.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:92:0)
[03:18:04.863] Timer tick, asking Buildbucket for the build status
[03:18:06.599] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:06.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:93:0) after 1m0s
[03:19:06.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:93:0)
[03:19:06.732] Timer tick, asking Buildbucket for the build status
[03:19:06.832] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:06.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:94:0) after 1m0s
[03:20:06.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:94:0)
[03:20:06.813] Timer tick, asking Buildbucket for the build status
[03:20:06.904] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:06.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:95:0) after 1m0s
[03:21:07.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:95:0)
[03:21:07.046] Timer tick, asking Buildbucket for the build status
[03:21:07.151] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:07.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:96:0) after 1m0s
[03:22:07.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:96:0)
[03:22:07.187] Timer tick, asking Buildbucket for the build status
[03:22:07.297] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:07.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:97:0) after 1m0s
[03:23:07.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:97:0)
[03:23:07.428] Timer tick, asking Buildbucket for the build status
[03:23:08.437] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:08.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:98:0) after 1m0s
[03:24:08.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:98:0)
[03:24:08.540] Timer tick, asking Buildbucket for the build status
[03:24:08.769] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:08.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:99:0) after 1m0s
[03:25:08.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:99:0)
[03:25:08.801] Timer tick, asking Buildbucket for the build status
[03:25:08.886] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:08.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:100:0) after 1m0s
[03:26:09.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:100:0)
[03:26:09.192] Timer tick, asking Buildbucket for the build status
[03:26:09.753] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:09.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:101:0) after 1m0s
[03:27:09.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:101:0)
[03:27:09.823] Timer tick, asking Buildbucket for the build status
[03:27:09.898] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:09.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:102:0) after 1m0s
[03:28:09.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:102:0)
[03:28:09.981] Timer tick, asking Buildbucket for the build status
[03:28:10.165] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:10.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:103:0) after 1m0s
[03:29:10.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:103:0)
[03:29:10.263] Timer tick, asking Buildbucket for the build status
[03:29:10.349] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:10.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:104:0) after 1m0s
[03:30:10.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:104:0)
[03:30:10.458] Timer tick, asking Buildbucket for the build status
[03:30:11.739] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:11.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:105:0) after 1m0s
[03:31:11.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:105:0)
[03:31:11.887] Timer tick, asking Buildbucket for the build status
[03:31:11.968] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:11.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:106:0) after 1m0s
[03:32:12.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:106:0)
[03:32:12.011] Timer tick, asking Buildbucket for the build status
[03:32:12.105] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:12.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:107:0) after 1m0s
[03:33:12.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:107:0)
[03:33:12.247] Timer tick, asking Buildbucket for the build status
[03:33:12.374] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:12.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:108:0) after 1m0s
[03:34:12.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:108:0)
[03:34:12.463] Timer tick, asking Buildbucket for the build status
[03:34:12.555] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:12.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:109:0) after 1m0s
[03:35:12.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:109:0)
[03:35:12.634] Timer tick, asking Buildbucket for the build status
[03:35:12.807] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:12.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:110:0) after 1m0s
[03:36:12.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:110:0)
[03:36:12.896] Timer tick, asking Buildbucket for the build status
[03:36:13.013] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:13.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:111:0) after 1m0s
[03:37:13.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:111:0)
[03:37:13.249] Timer tick, asking Buildbucket for the build status
[03:37:13.386] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:13.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:112:0) after 1m0s
[03:38:13.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:112:0)
[03:38:13.500] Timer tick, asking Buildbucket for the build status
[03:38:13.827] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:13.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:113:0) after 1m0s
[03:39:13.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:113:0)
[03:39:13.920] Timer tick, asking Buildbucket for the build status
[03:39:14.207] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:14.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:114:0) after 1m0s
[03:40:14.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:114:0)
[03:40:14.230] Timer tick, asking Buildbucket for the build status
[03:40:14.345] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:14.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:115:0) after 1m0s
[03:41:14.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:115:0)
[03:41:14.378] Timer tick, asking Buildbucket for the build status
[03:41:14.532] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:14.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:116:0) after 1m0s
[03:42:14.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:116:0)
[03:42:14.593] Timer tick, asking Buildbucket for the build status
[03:42:14.680] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:14.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:117:0) after 1m0s
[03:43:14.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:117:0)
[03:43:14.707] Timer tick, asking Buildbucket for the build status
[03:43:14.830] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:14.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:118:0) after 1m0s
[03:44:14.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:118:0)
[03:44:14.862] Timer tick, asking Buildbucket for the build status
[03:44:14.968] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:14.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:119:0) after 1m0s
[03:45:15.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:119:0)
[03:45:15.028] Timer tick, asking Buildbucket for the build status
[03:45:15.269] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:15.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:120:0) after 1m0s
[03:46:15.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:120:0)
[03:46:15.253] Timer tick, asking Buildbucket for the build status
[03:46:15.368] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:15.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:121:0) after 1m0s
[03:47:15.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:121:0)
[03:47:15.506] Timer tick, asking Buildbucket for the build status
[03:47:15.579] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:15.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:122:0) after 1m0s
[03:48:15.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:122:0)
[03:48:15.723] Timer tick, asking Buildbucket for the build status
[03:48:15.884] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:15.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:123:0) after 1m0s
[03:49:15.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:123:0)
[03:49:15.870] Timer tick, asking Buildbucket for the build status
[03:49:15.961] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:15.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:124:0) after 1m0s
[03:50:15.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:124:0)
[03:50:15.993] Timer tick, asking Buildbucket for the build status
[03:50:16.166] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:16.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:125:0) after 1m0s
[03:51:16.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:125:0)
[03:51:16.246] Timer tick, asking Buildbucket for the build status
[03:51:16.479] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:16.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:126:0) after 1m0s
[03:52:16.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:126:0)
[03:52:16.535] Timer tick, asking Buildbucket for the build status
[03:52:16.686] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:16.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:127:0) after 1m0s
[03:53:16.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:127:0)
[03:53:16.772] Timer tick, asking Buildbucket for the build status
[03:53:16.924] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:16.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:128:0) after 1m0s
[03:54:16.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:128:0)
[03:54:16.949] Timer tick, asking Buildbucket for the build status
[03:54:17.037] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:17.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:129:0) after 1m0s
[03:55:17.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:129:0)
[03:55:17.067] Timer tick, asking Buildbucket for the build status
[03:55:17.145] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:17.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:130:0) after 1m0s
[03:56:17.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:130:0)
[03:56:17.187] Timer tick, asking Buildbucket for the build status
[03:56:17.269] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:17.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:131:0) after 1m0s
[03:57:17.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:131:0)
[03:57:17.337] Timer tick, asking Buildbucket for the build status
[03:57:17.478] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:17.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:132:0) after 1m0s
[03:58:17.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:132:0)
[03:58:17.559] Timer tick, asking Buildbucket for the build status
[03:58:17.743] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:17.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:133:0) after 1m0s
[03:59:17.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:133:0)
[03:59:17.774] Timer tick, asking Buildbucket for the build status
[03:59:17.854] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:17.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:134:0) after 1m0s
[04:00:17.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:134:0)
[04:00:17.943] Timer tick, asking Buildbucket for the build status
[04:00:18.038] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:18.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:135:0) after 1m0s
[04:01:20.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:135:0)
[04:01:20.750] Timer tick, asking Buildbucket for the build status
[04:01:20.839] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:20.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:136:0) after 1m0s
[04:02:20.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:136:0)
[04:02:20.960] Timer tick, asking Buildbucket for the build status
[04:02:21.156] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:21.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:137:0) after 1m0s
[04:03:21.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:137:0)
[04:03:21.191] Timer tick, asking Buildbucket for the build status
[04:03:21.821] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:21.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:138:0) after 1m0s
[04:04:21.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:138:0)
[04:04:21.886] Timer tick, asking Buildbucket for the build status
[04:04:21.973] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:21.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:139:0) after 1m0s
[04:05:22.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:139:0)
[04:05:22.086] Timer tick, asking Buildbucket for the build status
[04:05:22.922] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:22.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:140:0) after 1m0s
[04:06:22.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:140:0)
[04:06:22.971] Timer tick, asking Buildbucket for the build status
[04:06:23.307] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:23.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:141:0) after 1m0s
[04:07:23.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:141:0)
[04:07:23.427] Timer tick, asking Buildbucket for the build status
[04:07:23.517] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:23.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:142:0) after 1m0s
[04:08:23.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:142:0)
[04:08:23.612] Timer tick, asking Buildbucket for the build status
[04:08:23.732] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:23.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:143:0) after 1m0s
[04:09:23.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:143:0)
[04:09:23.773] Timer tick, asking Buildbucket for the build status
[04:09:23.863] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:23.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:144:0) after 1m0s
[04:10:23.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:144:0)
[04:10:23.947] Timer tick, asking Buildbucket for the build status
[04:10:24.048] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:24.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:145:0) after 1m0s
[04:11:24.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:145:0)
[04:11:24.177] Timer tick, asking Buildbucket for the build status
[04:11:24.282] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:24.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:146:0) after 1m0s
[04:12:24.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:146:0)
[04:12:24.345] Timer tick, asking Buildbucket for the build status
[04:12:24.430] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:24.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:147:0) after 1m0s
[04:13:24.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:147:0)
[04:13:24.430] Timer tick, asking Buildbucket for the build status
[04:13:24.624] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:24.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:148:0) after 1m0s
[04:14:24.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:148:0)
[04:14:24.672] Timer tick, asking Buildbucket for the build status
[04:14:24.757] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:24.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:149:0) after 1m0s
[04:15:24.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:149:0)
[04:15:24.793] Timer tick, asking Buildbucket for the build status
[04:15:24.883] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:24.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:150:0) after 1m0s
[04:16:24.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:150:0)
[04:16:24.923] Timer tick, asking Buildbucket for the build status
[04:16:25.123] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:25.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:151:0) after 1m0s
[04:17:25.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:151:0)
[04:17:25.149] Timer tick, asking Buildbucket for the build status
[04:17:26.741] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:26.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:152:0) after 1m0s
[04:18:26.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:152:0)
[04:18:26.827] Timer tick, asking Buildbucket for the build status
[04:18:26.932] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:26.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:153:0) after 1m0s
[04:19:27.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:153:0)
[04:19:27.017] Timer tick, asking Buildbucket for the build status
[04:19:27.262] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:27.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:154:0) after 1m0s
[04:20:27.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:154:0)
[04:20:27.581] Timer tick, asking Buildbucket for the build status
[04:20:27.714] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:27.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:155:0) after 1m0s
[04:21:27.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:155:0)
[04:21:27.865] Timer tick, asking Buildbucket for the build status
[04:21:27.960] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:27.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:156:0) after 1m0s
[04:22:28.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:156:0)
[04:22:28.042] Timer tick, asking Buildbucket for the build status
[04:22:28.118] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:28.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:157:0) after 1m0s
[04:23:28.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:157:0)
[04:23:28.231] Timer tick, asking Buildbucket for the build status
[04:23:28.353] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:28.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:158:0) after 1m0s
[04:24:28.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:158:0)
[04:24:28.452] Timer tick, asking Buildbucket for the build status
[04:24:28.532] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:28.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:159:0) after 1m0s
[04:25:28.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:159:0)
[04:25:28.595] Timer tick, asking Buildbucket for the build status
[04:25:28.691] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:28.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:160:0) after 1m0s
[04:26:28.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:160:0)
[04:26:28.946] Timer tick, asking Buildbucket for the build status
[04:26:29.044] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:29.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:161:0) after 1m0s
[04:27:29.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:161:0)
[04:27:29.135] Timer tick, asking Buildbucket for the build status
[04:27:29.210] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:29.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:162:0) after 1m0s
[04:28:29.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:162:0)
[04:28:29.332] Timer tick, asking Buildbucket for the build status
[04:28:29.504] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:29.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:163:0) after 1m0s
[04:29:29.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:163:0)
[04:29:29.554] Timer tick, asking Buildbucket for the build status
[04:29:29.858] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:29.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:164:0) after 1m0s
[04:30:30.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:164:0)
[04:30:30.098] Timer tick, asking Buildbucket for the build status
[04:30:30.256] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:30.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:165:0) after 1m0s
[04:31:30.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:165:0)
[04:31:30.389] Timer tick, asking Buildbucket for the build status
[04:31:30.550] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:30.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:166:0) after 1m0s
[04:32:30.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:166:0)
[04:32:30.617] Timer tick, asking Buildbucket for the build status
[04:32:30.701] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:30.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:167:0) after 1m0s
[04:33:31.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:167:0)
[04:33:31.007] Timer tick, asking Buildbucket for the build status
[04:33:31.121] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:31.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:168:0) after 1m0s
[04:34:31.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:168:0)
[04:34:31.342] Timer tick, asking Buildbucket for the build status
[04:34:31.439] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:31.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:169:0) after 1m0s
[04:35:31.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:169:0)
[04:35:31.520] Timer tick, asking Buildbucket for the build status
[04:35:31.788] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:31.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:170:0) after 1m0s
[04:36:31.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:170:0)
[04:36:31.878] Timer tick, asking Buildbucket for the build status
[04:36:31.954] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:31.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:171:0) after 1m0s
[04:37:32.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:171:0)
[04:37:32.056] Timer tick, asking Buildbucket for the build status
[04:37:32.132] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:32.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:172:0) after 1m0s
[04:38:32.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:172:0)
[04:38:32.185] Timer tick, asking Buildbucket for the build status
[04:38:32.378] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:32.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:173:0) after 1m0s
[04:39:32.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:173:0)
[04:39:32.491] Timer tick, asking Buildbucket for the build status
[04:39:32.861] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:32.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:174:0) after 1m0s
[04:40:34.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:174:0)
[04:40:34.058] Timer tick, asking Buildbucket for the build status
[04:40:34.203] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:34.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:175:0) after 1m0s
[04:41:34.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:175:0)
[04:41:34.385] Timer tick, asking Buildbucket for the build status
[04:41:34.467] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:34.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:176:0) after 1m0s
[04:42:34.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:176:0)
[04:42:34.622] Timer tick, asking Buildbucket for the build status
[04:42:34.925] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:34.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:177:0) after 1m0s
[04:43:34.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:177:0)
[04:43:34.993] Timer tick, asking Buildbucket for the build status
[04:43:35.100] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:35.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:178:0) after 1m0s
[04:44:35.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:178:0)
[04:44:35.209] Timer tick, asking Buildbucket for the build status
[04:44:35.288] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:35.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:179:0) after 1m0s
[04:45:35.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:179:0)
[04:45:35.455] Timer tick, asking Buildbucket for the build status
[04:45:35.541] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:35.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:180:0) after 1m0s
[04:46:35.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:180:0)
[04:46:35.609] Timer tick, asking Buildbucket for the build status
[04:46:35.717] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:35.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:181:0) after 1m0s
[04:47:35.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:181:0)
[04:47:35.817] Timer tick, asking Buildbucket for the build status
[04:47:35.912] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:35.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:182:0) after 1m0s
[04:48:36.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:182:0)
[04:48:36.031] Timer tick, asking Buildbucket for the build status
[04:48:36.148] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:36.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:183:0) after 1m0s
[04:49:36.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:183:0)
[04:49:36.287] Timer tick, asking Buildbucket for the build status
[04:49:36.388] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:36.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:184:0) after 1m0s
[04:50:36.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:184:0)
[04:50:36.377] Timer tick, asking Buildbucket for the build status
[04:50:36.760] Build 8863967109839157856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:36.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029397171868511792:185:0) after 1m0s
[04:51:14.835] Received PubSub notification, asking Buildbucket for the build status
[04:51:14.909] Build 8863967109839157856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:51:14.909] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1605070272981682",
"created_by": "project:infra",
"created_ts": "1605059248084117",
"id": "8863967109839157856",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4319.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c53b9f92-95f9-41d4-b5d4-c62952fb5107\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4319.2\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-swfn\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605059582336252",
"status": "COMPLETED",
"status_changed_ts": "1605070273874841",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9029397171868511792",
"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/8863967109839157856/+/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:4fcdce0acb2a1310",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1605070274328050",
"url": "https://ci.chromium.org/b/8863967109839157856",
"utcnow_ts": "1605070274900158"
}
}
[04:51:14.909] Invocation finished in 3h3m48.14325336s with status SUCCEEDED