[13:44:18.298] New invocation is queued and will start shortly
[13:44:18.299] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:44:19.562] Starting the invocation (attempt 1)
[13:44:19.702] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:44:19.702] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:44:19.702] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9086337562889329744",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3713.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9086337562889329744",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[13:44:21.161] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550756659871931",
"id": "8920907500576911712",
"lease_expiration_ts": "1550875460991123",
"lease_key": "499567226",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3713.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550756660992012",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9086337562889329744",
"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/8920907500576911712/+/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:43291e4d04192910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1550756660992113",
"url": "https://ci.chromium.org/b/8920907500576911712",
"utcnow_ts": "1550756661147121"
}
}
[13:44:21.161] Task URL: https://ci.chromium.org/b/8920907500576911712
[13:44:21.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:2:0) after 1m0s
[13:45:21.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:2:0)
[13:45:21.182] Timer tick, asking Buildbucket for the build status
[13:45:21.478] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:21.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:3:0) after 1m0s
[13:46:21.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:3:0)
[13:46:21.504] Timer tick, asking Buildbucket for the build status
[13:46:21.578] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:46:21.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:4:0) after 1m0s
[13:47:21.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:4:0)
[13:47:21.594] Timer tick, asking Buildbucket for the build status
[13:47:21.650] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:21.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:5:0) after 1m0s
[13:48:21.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:5:0)
[13:48:21.671] Timer tick, asking Buildbucket for the build status
[13:48:21.747] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:48:21.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:6:0) after 1m0s
[13:49:21.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:6:0)
[13:49:21.768] Timer tick, asking Buildbucket for the build status
[13:49:21.838] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:21.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:7:0) after 1m0s
[13:50:21.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:7:0)
[13:50:21.858] Timer tick, asking Buildbucket for the build status
[13:50:21.968] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:21.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:8:0) after 1m0s
[13:51:21.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:8:0)
[13:51:21.990] Timer tick, asking Buildbucket for the build status
[13:51:22.036] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:22.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:9:0) after 1m0s
[13:52:22.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:9:0)
[13:52:22.058] Timer tick, asking Buildbucket for the build status
[13:52:22.208] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:22.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:10:0) after 1m0s
[13:53:22.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:10:0)
[13:53:22.227] Timer tick, asking Buildbucket for the build status
[13:53:22.278] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:53:22.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:11:0) after 1m0s
[13:54:22.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:11:0)
[13:54:22.296] Timer tick, asking Buildbucket for the build status
[13:54:22.364] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:54:22.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:12:0) after 1m0s
[13:55:22.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:12:0)
[13:55:22.382] Timer tick, asking Buildbucket for the build status
[13:55:22.511] Build 8920907500576911712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:55:22.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:13:0) after 1m0s
[13:55:49.017] Received PubSub notification, asking Buildbucket for the build status
[13:55:49.060] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:22.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:13:0)
[13:56:22.528] Timer tick, asking Buildbucket for the build status
[13:56:22.767] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:22.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:15:0) after 1m0s
[13:57:22.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:15:0)
[13:57:22.785] Timer tick, asking Buildbucket for the build status
[13:57:22.962] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:22.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:16:0) after 1m0s
[13:58:22.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:16:0)
[13:58:22.982] Timer tick, asking Buildbucket for the build status
[13:58:23.104] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:23.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:17:0) after 1m0s
[13:59:23.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:17:0)
[13:59:23.172] Timer tick, asking Buildbucket for the build status
[13:59:23.453] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:23.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:18:0) after 1m0s
[14:00:23.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:18:0)
[14:00:23.470] Timer tick, asking Buildbucket for the build status
[14:00:23.509] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:23.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:19:0) after 1m0s
[14:01:23.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:19:0)
[14:01:23.599] Timer tick, asking Buildbucket for the build status
[14:01:23.643] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:23.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:20:0) after 1m0s
[14:02:23.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:20:0)
[14:02:23.664] Timer tick, asking Buildbucket for the build status
[14:02:23.774] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:23.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:21:0) after 1m0s
[14:03:23.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:21:0)
[14:03:23.876] Timer tick, asking Buildbucket for the build status
[14:03:23.957] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:23.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:22:0) after 1m0s
[14:04:23.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:22:0)
[14:04:23.973] Timer tick, asking Buildbucket for the build status
[14:04:24.368] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:24.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:23:0) after 1m0s
[14:05:24.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:23:0)
[14:05:24.418] Timer tick, asking Buildbucket for the build status
[14:05:24.482] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:24.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:24:0) after 1m0s
[14:06:24.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:24:0)
[14:06:24.505] Timer tick, asking Buildbucket for the build status
[14:06:24.569] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:24.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:25:0) after 1m0s
[14:07:24.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:25:0)
[14:07:24.586] Timer tick, asking Buildbucket for the build status
[14:07:24.628] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:24.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:26:0) after 1m0s
[14:08:24.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:26:0)
[14:08:24.645] Timer tick, asking Buildbucket for the build status
[14:08:24.714] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:24.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:27:0) after 1m0s
[14:09:24.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:27:0)
[14:09:24.741] Timer tick, asking Buildbucket for the build status
[14:09:24.807] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:24.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:28:0) after 1m0s
[14:10:24.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:28:0)
[14:10:24.829] Timer tick, asking Buildbucket for the build status
[14:10:24.938] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:24.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:29:0) after 1m0s
[14:11:24.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:29:0)
[14:11:24.967] Timer tick, asking Buildbucket for the build status
[14:11:25.009] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:25.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:30:0) after 1m0s
[14:12:25.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:30:0)
[14:12:25.088] Timer tick, asking Buildbucket for the build status
[14:12:25.178] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:25.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:31:0) after 1m0s
[14:13:25.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:31:0)
[14:13:25.247] Timer tick, asking Buildbucket for the build status
[14:13:25.292] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:25.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:32:0) after 1m0s
[14:14:25.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:32:0)
[14:14:25.397] Timer tick, asking Buildbucket for the build status
[14:14:25.598] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:25.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:33:0) after 1m0s
[14:15:25.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:33:0)
[14:15:25.632] Timer tick, asking Buildbucket for the build status
[14:15:25.731] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:25.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:34:0) after 1m0s
[14:16:25.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:34:0)
[14:16:25.752] Timer tick, asking Buildbucket for the build status
[14:16:26.063] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:26.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:35:0) after 1m0s
[14:17:26.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:35:0)
[14:17:26.092] Timer tick, asking Buildbucket for the build status
[14:17:26.152] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:26.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:36:0) after 1m0s
[14:18:26.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:36:0)
[14:18:26.184] Timer tick, asking Buildbucket for the build status
[14:18:26.245] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:26.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:37:0) after 1m0s
[14:19:26.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:37:0)
[14:19:26.265] Timer tick, asking Buildbucket for the build status
[14:19:26.316] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:26.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:38:0) after 1m0s
[14:20:26.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:38:0)
[14:20:26.334] Timer tick, asking Buildbucket for the build status
[14:20:26.420] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:26.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:39:0) after 1m0s
[14:21:26.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:39:0)
[14:21:26.463] Timer tick, asking Buildbucket for the build status
[14:21:26.519] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:26.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:40:0) after 1m0s
[14:22:26.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:40:0)
[14:22:26.541] Timer tick, asking Buildbucket for the build status
[14:22:26.681] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:26.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:41:0) after 1m0s
[14:23:26.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:41:0)
[14:23:26.700] Timer tick, asking Buildbucket for the build status
[14:23:27.634] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:27.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:42:0) after 1m0s
[14:24:27.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:42:0)
[14:24:27.650] Timer tick, asking Buildbucket for the build status
[14:24:27.707] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:27.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:43:0) after 1m0s
[14:25:27.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:43:0)
[14:25:27.829] Timer tick, asking Buildbucket for the build status
[14:25:27.871] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:27.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:44:0) after 1m0s
[14:26:27.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:44:0)
[14:26:27.887] Timer tick, asking Buildbucket for the build status
[14:26:27.952] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:27.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:45:0) after 1m0s
[14:27:27.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:45:0)
[14:27:27.973] Timer tick, asking Buildbucket for the build status
[14:27:28.014] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:28.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:46:0) after 1m0s
[14:28:28.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:46:0)
[14:28:28.119] Timer tick, asking Buildbucket for the build status
[14:28:28.180] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:28.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:47:0) after 1m0s
[14:29:28.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:47:0)
[14:29:28.204] Timer tick, asking Buildbucket for the build status
[14:29:28.244] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:28.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:48:0) after 1m0s
[14:30:28.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:48:0)
[14:30:28.350] Timer tick, asking Buildbucket for the build status
[14:30:28.706] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:28.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:49:0) after 1m0s
[14:31:28.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:49:0)
[14:31:28.741] Timer tick, asking Buildbucket for the build status
[14:31:28.859] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:28.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:50:0) after 1m0s
[14:32:28.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:50:0)
[14:32:28.880] Timer tick, asking Buildbucket for the build status
[14:32:28.949] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:28.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:51:0) after 1m0s
[14:33:29.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:51:0)
[14:33:29.057] Timer tick, asking Buildbucket for the build status
[14:33:29.107] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:29.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:52:0) after 1m0s
[14:34:29.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:52:0)
[14:34:29.137] Timer tick, asking Buildbucket for the build status
[14:34:29.193] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:29.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:53:0) after 1m0s
[14:35:29.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:53:0)
[14:35:29.213] Timer tick, asking Buildbucket for the build status
[14:35:29.453] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:29.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:54:0) after 1m0s
[14:36:29.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:54:0)
[14:36:29.471] Timer tick, asking Buildbucket for the build status
[14:36:29.545] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:29.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:55:0) after 1m0s
[14:37:29.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:55:0)
[14:37:29.564] Timer tick, asking Buildbucket for the build status
[14:37:29.718] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:29.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:56:0) after 1m0s
[14:38:29.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:56:0)
[14:38:29.761] Timer tick, asking Buildbucket for the build status
[14:38:29.855] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:29.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:57:0) after 1m0s
[14:39:29.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:57:0)
[14:39:29.872] Timer tick, asking Buildbucket for the build status
[14:39:30.003] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:30.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:58:0) after 1m0s
[14:40:30.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:58:0)
[14:40:30.029] Timer tick, asking Buildbucket for the build status
[14:40:30.094] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:30.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:59:0) after 1m0s
[14:41:30.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:59:0)
[14:41:30.112] Timer tick, asking Buildbucket for the build status
[14:41:30.156] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:30.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:60:0) after 1m0s
[14:42:30.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:60:0)
[14:42:30.175] Timer tick, asking Buildbucket for the build status
[14:42:30.218] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:30.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:61:0) after 1m0s
[14:43:30.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:61:0)
[14:43:30.336] Timer tick, asking Buildbucket for the build status
[14:43:30.376] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:30.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:62:0) after 1m0s
[14:44:30.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:62:0)
[14:44:30.413] Timer tick, asking Buildbucket for the build status
[14:44:30.508] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:30.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:63:0) after 1m0s
[14:45:30.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:63:0)
[14:45:30.653] Timer tick, asking Buildbucket for the build status
[14:45:30.699] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:30.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:64:0) after 1m0s
[14:46:30.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:64:0)
[14:46:30.718] Timer tick, asking Buildbucket for the build status
[14:46:30.959] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:30.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:65:0) after 1m0s
[14:47:30.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:65:0)
[14:47:30.982] Timer tick, asking Buildbucket for the build status
[14:47:31.064] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:31.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:66:0) after 1m0s
[14:48:31.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:66:0)
[14:48:31.088] Timer tick, asking Buildbucket for the build status
[14:48:31.213] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:31.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:67:0) after 1m0s
[14:49:31.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:67:0)
[14:49:31.233] Timer tick, asking Buildbucket for the build status
[14:49:31.274] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:31.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:68:0) after 1m0s
[14:50:31.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:68:0)
[14:50:31.293] Timer tick, asking Buildbucket for the build status
[14:50:31.373] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:31.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:69:0) after 1m0s
[14:51:31.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:69:0)
[14:51:31.463] Timer tick, asking Buildbucket for the build status
[14:51:31.508] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:31.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:70:0) after 1m0s
[14:52:31.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:70:0)
[14:52:31.537] Timer tick, asking Buildbucket for the build status
[14:52:31.578] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:31.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:71:0) after 1m0s
[14:53:31.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:71:0)
[14:53:31.626] Timer tick, asking Buildbucket for the build status
[14:53:31.757] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:31.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:72:0) after 1m0s
[14:54:31.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:72:0)
[14:54:31.775] Timer tick, asking Buildbucket for the build status
[14:54:31.915] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:31.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:73:0) after 1m0s
[14:55:31.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:73:0)
[14:55:31.941] Timer tick, asking Buildbucket for the build status
[14:55:31.984] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:31.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:74:0) after 1m0s
[14:56:32.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:74:0)
[14:56:32.002] Timer tick, asking Buildbucket for the build status
[14:56:32.150] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:32.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:75:0) after 1m0s
[14:57:32.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:75:0)
[14:57:32.224] Timer tick, asking Buildbucket for the build status
[14:57:32.306] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:32.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:76:0) after 1m0s
[14:58:32.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:76:0)
[14:58:32.334] Timer tick, asking Buildbucket for the build status
[14:58:32.373] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:32.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:77:0) after 1m0s
[14:59:32.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:77:0)
[14:59:32.395] Timer tick, asking Buildbucket for the build status
[14:59:32.441] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:32.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:78:0) after 1m0s
[15:00:32.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:78:0)
[15:00:32.588] Timer tick, asking Buildbucket for the build status
[15:00:32.888] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:32.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:79:0) after 1m0s
[15:01:32.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:79:0)
[15:01:32.943] Timer tick, asking Buildbucket for the build status
[15:01:33.052] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:33.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:80:0) after 1m0s
[15:02:33.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:80:0)
[15:02:33.072] Timer tick, asking Buildbucket for the build status
[15:02:33.146] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:33.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:81:0) after 1m0s
[15:03:33.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:81:0)
[15:03:33.165] Timer tick, asking Buildbucket for the build status
[15:03:33.227] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:33.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:82:0) after 1m0s
[15:04:33.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:82:0)
[15:04:33.261] Timer tick, asking Buildbucket for the build status
[15:04:33.327] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:33.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:83:0) after 1m0s
[15:05:33.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:83:0)
[15:05:33.348] Timer tick, asking Buildbucket for the build status
[15:05:33.393] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:33.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:84:0) after 1m0s
[15:06:33.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:84:0)
[15:06:33.426] Timer tick, asking Buildbucket for the build status
[15:06:33.513] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:33.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:85:0) after 1m0s
[15:07:33.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:85:0)
[15:07:33.553] Timer tick, asking Buildbucket for the build status
[15:07:33.616] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:33.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:86:0) after 1m0s
[15:08:33.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:86:0)
[15:08:33.676] Timer tick, asking Buildbucket for the build status
[15:08:33.722] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:33.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:87:0) after 1m0s
[15:09:33.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:87:0)
[15:09:33.880] Timer tick, asking Buildbucket for the build status
[15:09:33.920] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:33.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:88:0) after 1m0s
[15:10:33.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:88:0)
[15:10:33.924] Timer tick, asking Buildbucket for the build status
[15:10:34.038] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:34.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:89:0) after 1m0s
[15:11:34.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:89:0)
[15:11:34.065] Timer tick, asking Buildbucket for the build status
[15:11:34.176] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:34.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:90:0) after 1m0s
[15:12:34.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:90:0)
[15:12:34.198] Timer tick, asking Buildbucket for the build status
[15:12:34.299] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:34.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:91:0) after 1m0s
[15:13:34.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:91:0)
[15:13:34.335] Timer tick, asking Buildbucket for the build status
[15:13:34.387] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:34.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:92:0) after 1m0s
[15:14:34.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:92:0)
[15:14:34.406] Timer tick, asking Buildbucket for the build status
[15:14:34.452] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:93:0) after 1m0s
[15:15:34.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:93:0)
[15:15:34.471] Timer tick, asking Buildbucket for the build status
[15:15:34.564] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:34.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:94:0) after 1m0s
[15:16:34.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:94:0)
[15:16:34.583] Timer tick, asking Buildbucket for the build status
[15:16:34.631] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:34.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:95:0) after 1m0s
[15:17:34.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:95:0)
[15:17:34.663] Timer tick, asking Buildbucket for the build status
[15:17:34.706] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:34.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:96:0) after 1m0s
[15:18:34.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:96:0)
[15:18:34.733] Timer tick, asking Buildbucket for the build status
[15:18:34.845] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:34.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:97:0) after 1m0s
[15:19:34.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:97:0)
[15:19:34.862] Timer tick, asking Buildbucket for the build status
[15:19:34.912] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:34.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:98:0) after 1m0s
[15:20:34.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:98:0)
[15:20:34.940] Timer tick, asking Buildbucket for the build status
[15:20:35.051] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:35.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:99:0) after 1m0s
[15:21:35.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:99:0)
[15:21:35.083] Timer tick, asking Buildbucket for the build status
[15:21:35.155] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:35.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:100:0) after 1m0s
[15:22:35.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:100:0)
[15:22:35.181] Timer tick, asking Buildbucket for the build status
[15:22:35.308] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:35.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:101:0) after 1m0s
[15:23:35.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:101:0)
[15:23:35.333] Timer tick, asking Buildbucket for the build status
[15:23:35.414] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:35.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:102:0) after 1m0s
[15:24:35.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:102:0)
[15:24:35.467] Timer tick, asking Buildbucket for the build status
[15:24:35.950] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:35.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:103:0) after 1m0s
[15:25:35.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:103:0)
[15:25:35.972] Timer tick, asking Buildbucket for the build status
[15:25:36.045] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:36.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:104:0) after 1m0s
[15:26:36.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:104:0)
[15:26:36.064] Timer tick, asking Buildbucket for the build status
[15:26:36.106] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:36.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:105:0) after 1m0s
[15:27:36.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:105:0)
[15:27:36.134] Timer tick, asking Buildbucket for the build status
[15:27:36.173] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:36.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:106:0) after 1m0s
[15:28:36.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:106:0)
[15:28:36.198] Timer tick, asking Buildbucket for the build status
[15:28:36.238] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:36.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:107:0) after 1m0s
[15:29:36.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:107:0)
[15:29:36.267] Timer tick, asking Buildbucket for the build status
[15:29:36.305] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:36.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:108:0) after 1m0s
[15:30:36.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:108:0)
[15:30:36.332] Timer tick, asking Buildbucket for the build status
[15:30:36.372] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:36.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:109:0) after 1m0s
[15:31:36.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:109:0)
[15:31:36.404] Timer tick, asking Buildbucket for the build status
[15:31:36.444] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:36.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:110:0) after 1m0s
[15:32:36.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:110:0)
[15:32:36.480] Timer tick, asking Buildbucket for the build status
[15:32:36.644] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:36.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:111:0) after 1m0s
[15:33:36.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:111:0)
[15:33:36.689] Timer tick, asking Buildbucket for the build status
[15:33:36.817] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:36.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:112:0) after 1m0s
[15:34:36.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:112:0)
[15:34:36.915] Timer tick, asking Buildbucket for the build status
[15:34:36.958] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:36.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:113:0) after 1m0s
[15:35:36.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:113:0)
[15:35:36.983] Timer tick, asking Buildbucket for the build status
[15:35:37.145] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:37.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:114:0) after 1m0s
[15:36:37.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:114:0)
[15:36:37.166] Timer tick, asking Buildbucket for the build status
[15:36:37.241] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:37.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:115:0) after 1m0s
[15:37:37.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:115:0)
[15:37:37.268] Timer tick, asking Buildbucket for the build status
[15:37:37.404] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:37.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:116:0) after 1m0s
[15:38:37.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:116:0)
[15:38:37.442] Timer tick, asking Buildbucket for the build status
[15:38:37.562] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:37.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:117:0) after 1m0s
[15:39:37.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:117:0)
[15:39:37.591] Timer tick, asking Buildbucket for the build status
[15:39:37.687] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:37.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:118:0) after 1m0s
[15:40:37.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:118:0)
[15:40:37.744] Timer tick, asking Buildbucket for the build status
[15:40:37.782] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:37.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:119:0) after 1m0s
[15:41:37.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:119:0)
[15:41:37.805] Timer tick, asking Buildbucket for the build status
[15:41:37.850] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:37.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:120:0) after 1m0s
[15:42:37.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:120:0)
[15:42:37.910] Timer tick, asking Buildbucket for the build status
[15:42:38.029] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:38.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:121:0) after 1m0s
[15:43:38.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:121:0)
[15:43:38.051] Timer tick, asking Buildbucket for the build status
[15:43:38.188] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:38.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:122:0) after 1m0s
[15:44:38.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:122:0)
[15:44:38.211] Timer tick, asking Buildbucket for the build status
[15:44:38.254] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:38.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:123:0) after 1m0s
[15:45:38.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:123:0)
[15:45:38.278] Timer tick, asking Buildbucket for the build status
[15:45:38.348] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:38.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:124:0) after 1m0s
[15:46:38.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:124:0)
[15:46:38.373] Timer tick, asking Buildbucket for the build status
[15:46:38.412] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:38.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:125:0) after 1m0s
[15:47:38.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:125:0)
[15:47:38.439] Timer tick, asking Buildbucket for the build status
[15:47:38.480] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:38.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:126:0) after 1m0s
[15:48:38.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:126:0)
[15:48:38.502] Timer tick, asking Buildbucket for the build status
[15:48:38.544] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:38.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:127:0) after 1m0s
[15:49:38.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:127:0)
[15:49:38.573] Timer tick, asking Buildbucket for the build status
[15:49:38.620] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:38.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:128:0) after 1m0s
[15:50:38.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:128:0)
[15:50:38.649] Timer tick, asking Buildbucket for the build status
[15:50:38.706] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:38.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:129:0) after 1m0s
[15:51:38.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:129:0)
[15:51:38.746] Timer tick, asking Buildbucket for the build status
[15:51:38.790] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:38.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:130:0) after 1m0s
[15:52:38.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:130:0)
[15:52:38.809] Timer tick, asking Buildbucket for the build status
[15:52:38.895] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:38.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:131:0) after 1m0s
[15:53:38.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:131:0)
[15:53:38.918] Timer tick, asking Buildbucket for the build status
[15:53:38.960] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:38.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:132:0) after 1m0s
[15:54:38.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:132:0)
[15:54:38.980] Timer tick, asking Buildbucket for the build status
[15:54:39.277] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:39.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:133:0) after 1m0s
[15:55:39.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:133:0)
[15:55:39.309] Timer tick, asking Buildbucket for the build status
[15:55:39.384] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:39.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:134:0) after 1m0s
[15:56:39.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:134:0)
[15:56:39.418] Timer tick, asking Buildbucket for the build status
[15:56:39.497] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:39.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:135:0) after 1m0s
[15:57:39.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:135:0)
[15:57:39.520] Timer tick, asking Buildbucket for the build status
[15:57:39.571] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:39.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:136:0) after 1m0s
[15:58:39.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:136:0)
[15:58:39.592] Timer tick, asking Buildbucket for the build status
[15:58:39.754] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:39.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:137:0) after 1m0s
[15:59:39.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:137:0)
[15:59:39.773] Timer tick, asking Buildbucket for the build status
[15:59:39.855] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:39.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:138:0) after 1m0s
[16:00:39.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:138:0)
[16:00:39.878] Timer tick, asking Buildbucket for the build status
[16:00:39.953] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:39.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:139:0) after 1m0s
[16:01:40.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:139:0)
[16:01:40.030] Timer tick, asking Buildbucket for the build status
[16:01:40.092] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:40.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:140:0) after 1m0s
[16:02:40.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:140:0)
[16:02:40.123] Timer tick, asking Buildbucket for the build status
[16:02:40.241] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:40.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:141:0) after 1m0s
[16:03:40.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:141:0)
[16:03:40.266] Timer tick, asking Buildbucket for the build status
[16:03:40.397] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:40.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:142:0) after 1m0s
[16:04:40.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:142:0)
[16:04:40.430] Timer tick, asking Buildbucket for the build status
[16:04:40.525] Build 8920907500576911712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:40.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086337562889329744:143:0) after 1m0s
[16:04:42.889] Received PubSub notification, asking Buildbucket for the build status
[16:04:42.933] Build 8920907500576911712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:04:42.933] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1550765082185156",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550756659871931",
"id": "8920907500576911712",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3713.0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4115-b99fdd5\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-32-0258d129-us-west1-c-jw8p\"], \"pool\": [\"luci.infra.cron\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550756659871931, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9086337562889329744\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8920907500576911712\"}}, \"version\": \"74.0.3713.0\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9086337562889329744\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"3dab7a70bce848a6486670afc32d65ade65b8058\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920907500576911712\", \"input\": {}, \"createTime\": \"2019-02-21T13:44:19.871931Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-west1-c-jw8p\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550757341255298",
"status": "COMPLETED",
"status_changed_ts": "1550765082596460",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9086337562889329744",
"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/8920907500576911712/+/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:43291e4d04192910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1550765082597481",
"url": "https://ci.chromium.org/b/8920907500576911712",
"utcnow_ts": "1550765082920189"
}
}
[16:04:42.933] Invocation finished in 2h20m24.650500792s with status SUCCEEDED