[10:55:24.158] New invocation is queued and will start shortly
[10:55:24.158] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:55:25.237] Starting the invocation (attempt 1)
[10:55:25.269] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:55:25.269] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:55:25.269] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084355056063424080",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3734.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:9084355056063424080",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:55:26.129] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552647325360905",
"id": "8918924994121591872",
"lease_expiration_ts": "1552766125993343",
"lease_key": "1093759276",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3734.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552647325994781",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084355056063424080",
"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/8918924994121591872/+/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:4399cf8ffe417810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552647325994861",
"url": "https://ci.chromium.org/b/8918924994121591872",
"utcnow_ts": "1552647326120623"
}
}
[10:55:26.129] Task URL: https://ci.chromium.org/b/8918924994121591872
[10:55:26.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:2:0) after 1m0s
[10:56:03.358] Received PubSub notification, asking Buildbucket for the build status
[10:56:03.416] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:26.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:2:0)
[10:56:26.125] Timer tick, asking Buildbucket for the build status
[10:56:26.166] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:26.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:4:0) after 1m0s
[10:57:26.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:4:0)
[10:57:26.193] Timer tick, asking Buildbucket for the build status
[10:57:26.269] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:26.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:5:0) after 1m0s
[10:58:26.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:5:0)
[10:58:26.272] Timer tick, asking Buildbucket for the build status
[10:58:26.405] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:26.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:6:0) after 1m0s
[10:59:26.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:6:0)
[10:59:26.382] Timer tick, asking Buildbucket for the build status
[10:59:26.693] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:26.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:7:0) after 1m0s
[11:00:26.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:7:0)
[11:00:26.686] Timer tick, asking Buildbucket for the build status
[11:00:26.723] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:26.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:8:0) after 1m0s
[11:01:26.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:8:0)
[11:01:26.704] Timer tick, asking Buildbucket for the build status
[11:01:26.742] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:26.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:9:0) after 1m0s
[11:02:26.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:9:0)
[11:02:26.760] Timer tick, asking Buildbucket for the build status
[11:02:26.854] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:26.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:10:0) after 1m0s
[11:03:26.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:10:0)
[11:03:26.883] Timer tick, asking Buildbucket for the build status
[11:03:27.062] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:27.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:11:0) after 1m0s
[11:04:27.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:11:0)
[11:04:27.083] Timer tick, asking Buildbucket for the build status
[11:04:27.206] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:27.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:12:0) after 1m0s
[11:05:27.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:12:0)
[11:05:27.225] Timer tick, asking Buildbucket for the build status
[11:05:27.305] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:27.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:13:0) after 1m0s
[11:06:27.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:13:0)
[11:06:27.326] Timer tick, asking Buildbucket for the build status
[11:06:27.382] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:27.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:14:0) after 1m0s
[11:07:27.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:14:0)
[11:07:27.406] Timer tick, asking Buildbucket for the build status
[11:07:27.514] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:27.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:15:0) after 1m0s
[11:08:27.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:15:0)
[11:08:27.533] Timer tick, asking Buildbucket for the build status
[11:08:27.824] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:27.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:16:0) after 1m0s
[11:09:27.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:16:0)
[11:09:27.821] Timer tick, asking Buildbucket for the build status
[11:09:27.875] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:27.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:17:0) after 1m0s
[11:10:27.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:17:0)
[11:10:27.894] Timer tick, asking Buildbucket for the build status
[11:10:27.985] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:27.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:18:0) after 1m0s
[11:11:27.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:18:0)
[11:11:27.995] Timer tick, asking Buildbucket for the build status
[11:11:28.179] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:28.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:19:0) after 1m0s
[11:12:28.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:19:0)
[11:12:28.198] Timer tick, asking Buildbucket for the build status
[11:12:28.270] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:28.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:20:0) after 1m0s
[11:13:28.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:20:0)
[11:13:28.291] Timer tick, asking Buildbucket for the build status
[11:13:28.407] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:28.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:21:0) after 1m0s
[11:14:28.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:21:0)
[11:14:28.427] Timer tick, asking Buildbucket for the build status
[11:14:28.483] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:28.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:22:0) after 1m0s
[11:15:28.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:22:0)
[11:15:28.502] Timer tick, asking Buildbucket for the build status
[11:15:28.582] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:28.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:23:0) after 1m0s
[11:16:28.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:23:0)
[11:16:28.580] Timer tick, asking Buildbucket for the build status
[11:16:28.658] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:28.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:24:0) after 1m0s
[11:17:28.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:24:0)
[11:17:28.676] Timer tick, asking Buildbucket for the build status
[11:17:28.745] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:28.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:25:0) after 1m0s
[11:18:28.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:25:0)
[11:18:28.743] Timer tick, asking Buildbucket for the build status
[11:18:28.880] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:28.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:26:0) after 1m0s
[11:19:28.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:26:0)
[11:19:28.894] Timer tick, asking Buildbucket for the build status
[11:19:28.956] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:28.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:27:0) after 1m0s
[11:20:28.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:27:0)
[11:20:28.949] Timer tick, asking Buildbucket for the build status
[11:20:28.988] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:28.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:28:0) after 1m0s
[11:21:29.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:28:0)
[11:21:29.009] Timer tick, asking Buildbucket for the build status
[11:21:29.144] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:29.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:29:0) after 1m0s
[11:22:29.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:29:0)
[11:22:29.127] Timer tick, asking Buildbucket for the build status
[11:22:29.253] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:29.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:30:0) after 1m0s
[11:23:29.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:30:0)
[11:23:29.223] Timer tick, asking Buildbucket for the build status
[11:23:29.269] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:29.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:31:0) after 1m0s
[11:24:29.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:31:0)
[11:24:29.291] Timer tick, asking Buildbucket for the build status
[11:24:29.343] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:29.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:32:0) after 1m0s
[11:25:29.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:32:0)
[11:25:29.330] Timer tick, asking Buildbucket for the build status
[11:25:29.394] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:29.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:33:0) after 1m0s
[11:26:29.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:33:0)
[11:26:29.410] Timer tick, asking Buildbucket for the build status
[11:26:29.618] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:29.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:34:0) after 1m0s
[11:27:29.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:34:0)
[11:27:29.607] Timer tick, asking Buildbucket for the build status
[11:27:29.660] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:29.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:35:0) after 1m0s
[11:28:29.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:35:0)
[11:28:29.640] Timer tick, asking Buildbucket for the build status
[11:28:29.696] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:29.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:36:0) after 1m0s
[11:29:29.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:36:0)
[11:29:29.698] Timer tick, asking Buildbucket for the build status
[11:29:29.790] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:29.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:37:0) after 1m0s
[11:30:29.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:37:0)
[11:30:29.808] Timer tick, asking Buildbucket for the build status
[11:30:29.961] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:29.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:38:0) after 1m0s
[11:31:29.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:38:0)
[11:31:29.950] Timer tick, asking Buildbucket for the build status
[11:31:30.082] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:30.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:39:0) after 1m0s
[11:32:30.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:39:0)
[11:32:30.085] Timer tick, asking Buildbucket for the build status
[11:32:30.405] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:30.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:40:0) after 1m0s
[11:33:30.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:40:0)
[11:33:30.379] Timer tick, asking Buildbucket for the build status
[11:33:30.436] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:30.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:41:0) after 1m0s
[11:34:30.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:41:0)
[11:34:30.465] Timer tick, asking Buildbucket for the build status
[11:34:30.745] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:30.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:42:0) after 1m0s
[11:35:30.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:42:0)
[11:35:30.757] Timer tick, asking Buildbucket for the build status
[11:35:31.018] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:31.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:43:0) after 1m0s
[11:36:30.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:43:0)
[11:36:30.994] Timer tick, asking Buildbucket for the build status
[11:36:31.102] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:31.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:44:0) after 1m0s
[11:37:31.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:44:0)
[11:37:31.121] Timer tick, asking Buildbucket for the build status
[11:37:31.171] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:31.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:45:0) after 1m0s
[11:38:31.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:45:0)
[11:38:31.184] Timer tick, asking Buildbucket for the build status
[11:38:31.257] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:31.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:46:0) after 1m0s
[11:39:31.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:46:0)
[11:39:31.277] Timer tick, asking Buildbucket for the build status
[11:39:31.382] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:31.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:47:0) after 1m0s
[11:40:31.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:47:0)
[11:40:31.390] Timer tick, asking Buildbucket for the build status
[11:40:31.426] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:31.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:48:0) after 1m0s
[11:41:31.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:48:0)
[11:41:31.446] Timer tick, asking Buildbucket for the build status
[11:41:31.623] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:31.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:49:0) after 1m0s
[11:42:31.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:49:0)
[11:42:31.643] Timer tick, asking Buildbucket for the build status
[11:42:31.689] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:31.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:50:0) after 1m0s
[11:43:31.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:50:0)
[11:43:31.710] Timer tick, asking Buildbucket for the build status
[11:43:31.763] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:31.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:51:0) after 1m0s
[11:44:31.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:51:0)
[11:44:31.782] Timer tick, asking Buildbucket for the build status
[11:44:31.877] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:31.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:52:0) after 1m0s
[11:45:31.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:52:0)
[11:45:31.907] Timer tick, asking Buildbucket for the build status
[11:45:32.010] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:32.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:53:0) after 1m0s
[11:46:31.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:53:0)
[11:46:31.990] Timer tick, asking Buildbucket for the build status
[11:46:32.031] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:32.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:54:0) after 1m0s
[11:47:32.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:54:0)
[11:47:32.042] Timer tick, asking Buildbucket for the build status
[11:47:32.191] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:32.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:55:0) after 1m0s
[11:48:32.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:55:0)
[11:48:32.220] Timer tick, asking Buildbucket for the build status
[11:48:32.315] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:32.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:56:0) after 1m0s
[11:49:32.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:56:0)
[11:49:32.336] Timer tick, asking Buildbucket for the build status
[11:49:32.429] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:32.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:57:0) after 1m0s
[11:50:32.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:57:0)
[11:50:32.416] Timer tick, asking Buildbucket for the build status
[11:50:32.529] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:32.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:58:0) after 1m0s
[11:51:32.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:58:0)
[11:51:32.549] Timer tick, asking Buildbucket for the build status
[11:51:32.711] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:32.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:59:0) after 1m0s
[11:52:32.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:59:0)
[11:52:32.708] Timer tick, asking Buildbucket for the build status
[11:52:32.815] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:32.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:60:0) after 1m0s
[11:53:32.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:60:0)
[11:53:32.815] Timer tick, asking Buildbucket for the build status
[11:53:32.968] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:32.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:61:0) after 1m0s
[11:54:32.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:61:0)
[11:54:32.990] Timer tick, asking Buildbucket for the build status
[11:54:33.024] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:33.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:62:0) after 1m0s
[11:55:33.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:62:0)
[11:55:33.045] Timer tick, asking Buildbucket for the build status
[11:55:33.117] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:33.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:63:0) after 1m0s
[11:56:33.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:63:0)
[11:56:33.128] Timer tick, asking Buildbucket for the build status
[11:56:33.169] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:33.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:64:0) after 1m0s
[11:57:33.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:64:0)
[11:57:33.204] Timer tick, asking Buildbucket for the build status
[11:57:33.416] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:33.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:65:0) after 1m0s
[11:58:33.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:65:0)
[11:58:33.453] Timer tick, asking Buildbucket for the build status
[11:58:33.485] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:33.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:66:0) after 1m0s
[11:59:33.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:66:0)
[11:59:33.503] Timer tick, asking Buildbucket for the build status
[11:59:33.554] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:33.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:67:0) after 1m0s
[12:00:33.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:67:0)
[12:00:33.567] Timer tick, asking Buildbucket for the build status
[12:00:33.839] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:33.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:68:0) after 1m0s
[12:01:33.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:68:0)
[12:01:33.860] Timer tick, asking Buildbucket for the build status
[12:01:33.895] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:33.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:69:0) after 1m0s
[12:02:33.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:69:0)
[12:02:33.916] Timer tick, asking Buildbucket for the build status
[12:02:34.002] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:34.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:70:0) after 1m0s
[12:03:34.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:70:0)
[12:03:34.015] Timer tick, asking Buildbucket for the build status
[12:03:34.139] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:34.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:71:0) after 1m0s
[12:04:34.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:71:0)
[12:04:34.145] Timer tick, asking Buildbucket for the build status
[12:04:34.200] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:34.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:72:0) after 1m0s
[12:05:34.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:72:0)
[12:05:34.220] Timer tick, asking Buildbucket for the build status
[12:05:34.290] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:34.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:73:0) after 1m0s
[12:06:34.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:73:0)
[12:06:34.302] Timer tick, asking Buildbucket for the build status
[12:06:34.341] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:34.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:74:0) after 1m0s
[12:07:34.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:74:0)
[12:07:34.374] Timer tick, asking Buildbucket for the build status
[12:07:34.486] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:34.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:75:0) after 1m0s
[12:08:34.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:75:0)
[12:08:34.471] Timer tick, asking Buildbucket for the build status
[12:08:34.577] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:34.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:76:0) after 1m0s
[12:09:34.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:76:0)
[12:09:34.578] Timer tick, asking Buildbucket for the build status
[12:09:34.642] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:34.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:77:0) after 1m0s
[12:10:34.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:77:0)
[12:10:34.660] Timer tick, asking Buildbucket for the build status
[12:10:34.699] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:34.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:78:0) after 1m0s
[12:11:34.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:78:0)
[12:11:34.675] Timer tick, asking Buildbucket for the build status
[12:11:34.731] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:34.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:79:0) after 1m0s
[12:12:34.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:79:0)
[12:12:34.736] Timer tick, asking Buildbucket for the build status
[12:12:34.848] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:34.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:80:0) after 1m0s
[12:13:34.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:80:0)
[12:13:34.841] Timer tick, asking Buildbucket for the build status
[12:13:34.895] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:34.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:81:0) after 1m0s
[12:14:34.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:81:0)
[12:14:34.919] Timer tick, asking Buildbucket for the build status
[12:14:34.962] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:34.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:82:0) after 1m0s
[12:15:34.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:82:0)
[12:15:34.982] Timer tick, asking Buildbucket for the build status
[12:15:35.250] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:35.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:83:0) after 1m0s
[12:16:35.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:83:0)
[12:16:35.270] Timer tick, asking Buildbucket for the build status
[12:16:35.400] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:35.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:84:0) after 1m0s
[12:17:35.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:84:0)
[12:17:35.432] Timer tick, asking Buildbucket for the build status
[12:17:35.508] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:35.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:85:0) after 1m0s
[12:18:35.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:85:0)
[12:18:35.546] Timer tick, asking Buildbucket for the build status
[12:18:35.582] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:35.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:86:0) after 1m0s
[12:19:35.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:86:0)
[12:19:35.573] Timer tick, asking Buildbucket for the build status
[12:19:35.692] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:35.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:87:0) after 1m0s
[12:20:35.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:87:0)
[12:20:35.722] Timer tick, asking Buildbucket for the build status
[12:20:35.761] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:35.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:88:0) after 1m0s
[12:21:35.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:88:0)
[12:21:35.741] Timer tick, asking Buildbucket for the build status
[12:21:35.805] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:35.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:89:0) after 1m0s
[12:22:35.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:89:0)
[12:22:35.807] Timer tick, asking Buildbucket for the build status
[12:22:35.843] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:35.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:90:0) after 1m0s
[12:23:35.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:90:0)
[12:23:35.869] Timer tick, asking Buildbucket for the build status
[12:23:35.934] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:35.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:91:0) after 1m0s
[12:24:35.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:91:0)
[12:24:35.906] Timer tick, asking Buildbucket for the build status
[12:24:35.977] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:35.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:92:0) after 1m0s
[12:25:36.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:92:0)
[12:25:36.001] Timer tick, asking Buildbucket for the build status
[12:25:36.107] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:36.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:93:0) after 1m0s
[12:26:36.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:93:0)
[12:26:36.125] Timer tick, asking Buildbucket for the build status
[12:26:36.182] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:36.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:94:0) after 1m0s
[12:27:36.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:94:0)
[12:27:36.162] Timer tick, asking Buildbucket for the build status
[12:27:36.194] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:36.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:95:0) after 1m0s
[12:28:36.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:95:0)
[12:28:36.207] Timer tick, asking Buildbucket for the build status
[12:28:36.342] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:36.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:96:0) after 1m0s
[12:29:36.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:96:0)
[12:29:36.369] Timer tick, asking Buildbucket for the build status
[12:29:36.408] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:36.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:97:0) after 1m0s
[12:30:36.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:97:0)
[12:30:36.451] Timer tick, asking Buildbucket for the build status
[12:30:36.521] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:36.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:98:0) after 1m0s
[12:31:36.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:98:0)
[12:31:36.492] Timer tick, asking Buildbucket for the build status
[12:31:36.533] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:36.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:99:0) after 1m0s
[12:32:36.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:99:0)
[12:32:36.521] Timer tick, asking Buildbucket for the build status
[12:32:36.652] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:36.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:100:0) after 1m0s
[12:33:36.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:100:0)
[12:33:36.671] Timer tick, asking Buildbucket for the build status
[12:33:36.705] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:36.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:101:0) after 1m0s
[12:34:36.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:101:0)
[12:34:36.774] Timer tick, asking Buildbucket for the build status
[12:34:36.827] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:36.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:102:0) after 1m0s
[12:35:36.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:102:0)
[12:35:36.860] Timer tick, asking Buildbucket for the build status
[12:35:36.910] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:36.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:103:0) after 1m0s
[12:36:36.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:103:0)
[12:36:36.935] Timer tick, asking Buildbucket for the build status
[12:36:37.014] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:37.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:104:0) after 1m0s
[12:37:37.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:104:0)
[12:37:37.032] Timer tick, asking Buildbucket for the build status
[12:37:37.173] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:37.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:105:0) after 1m0s
[12:38:37.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:105:0)
[12:38:37.203] Timer tick, asking Buildbucket for the build status
[12:38:37.239] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:37.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:106:0) after 1m0s
[12:39:37.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:106:0)
[12:39:37.216] Timer tick, asking Buildbucket for the build status
[12:39:37.262] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:37.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:107:0) after 1m0s
[12:40:37.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:107:0)
[12:40:37.285] Timer tick, asking Buildbucket for the build status
[12:40:37.320] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:37.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:108:0) after 1m0s
[12:41:37.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:108:0)
[12:41:37.320] Timer tick, asking Buildbucket for the build status
[12:41:37.372] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:37.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:109:0) after 1m0s
[12:42:37.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:109:0)
[12:42:37.395] Timer tick, asking Buildbucket for the build status
[12:42:37.500] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:37.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:110:0) after 1m0s
[12:43:37.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:110:0)
[12:43:37.501] Timer tick, asking Buildbucket for the build status
[12:43:37.563] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:37.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:111:0) after 1m0s
[12:44:37.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:111:0)
[12:44:37.585] Timer tick, asking Buildbucket for the build status
[12:44:37.620] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:37.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:112:0) after 1m0s
[12:45:37.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:112:0)
[12:45:37.616] Timer tick, asking Buildbucket for the build status
[12:45:37.672] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:37.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:113:0) after 1m0s
[12:46:37.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:113:0)
[12:46:37.734] Timer tick, asking Buildbucket for the build status
[12:46:37.895] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:37.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:114:0) after 1m0s
[12:47:37.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:114:0)
[12:47:37.919] Timer tick, asking Buildbucket for the build status
[12:47:37.994] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:37.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:115:0) after 1m0s
[12:48:38.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:115:0)
[12:48:38.013] Timer tick, asking Buildbucket for the build status
[12:48:38.067] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:38.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:116:0) after 1m0s
[12:49:38.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:116:0)
[12:49:38.055] Timer tick, asking Buildbucket for the build status
[12:49:38.123] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:38.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:117:0) after 1m0s
[12:50:38.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:117:0)
[12:50:38.153] Timer tick, asking Buildbucket for the build status
[12:50:38.206] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:38.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:118:0) after 1m0s
[12:51:38.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:118:0)
[12:51:38.227] Timer tick, asking Buildbucket for the build status
[12:51:38.288] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:38.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:119:0) after 1m0s
[12:52:38.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:119:0)
[12:52:38.319] Timer tick, asking Buildbucket for the build status
[12:52:38.404] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:38.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:120:0) after 1m0s
[12:53:38.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:120:0)
[12:53:38.397] Timer tick, asking Buildbucket for the build status
[12:53:38.451] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:38.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:121:0) after 1m0s
[12:54:38.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:121:0)
[12:54:38.450] Timer tick, asking Buildbucket for the build status
[12:54:38.485] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:38.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:122:0) after 1m0s
[12:55:38.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:122:0)
[12:55:38.498] Timer tick, asking Buildbucket for the build status
[12:55:38.616] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:38.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:123:0) after 1m0s
[12:56:38.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:123:0)
[12:56:38.641] Timer tick, asking Buildbucket for the build status
[12:56:38.684] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:38.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:124:0) after 1m0s
[12:57:38.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:124:0)
[12:57:38.682] Timer tick, asking Buildbucket for the build status
[12:57:38.737] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:38.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:125:0) after 1m0s
[12:58:38.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:125:0)
[12:58:38.751] Timer tick, asking Buildbucket for the build status
[12:58:39.054] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:39.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:126:0) after 1m0s
[12:59:39.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:126:0)
[12:59:39.026] Timer tick, asking Buildbucket for the build status
[12:59:39.075] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:39.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:127:0) after 1m0s
[13:00:39.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:127:0)
[13:00:39.095] Timer tick, asking Buildbucket for the build status
[13:00:39.296] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:39.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:128:0) after 1m0s
[13:01:39.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:128:0)
[13:01:39.322] Timer tick, asking Buildbucket for the build status
[13:01:39.383] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:39.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:129:0) after 1m0s
[13:02:39.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:129:0)
[13:02:39.409] Timer tick, asking Buildbucket for the build status
[13:02:39.508] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:39.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:130:0) after 1m0s
[13:03:39.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:130:0)
[13:03:39.501] Timer tick, asking Buildbucket for the build status
[13:03:39.536] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:39.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:131:0) after 1m0s
[13:04:39.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:131:0)
[13:04:39.558] Timer tick, asking Buildbucket for the build status
[13:04:39.706] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:39.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:132:0) after 1m0s
[13:05:39.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:132:0)
[13:05:39.728] Timer tick, asking Buildbucket for the build status
[13:05:39.873] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:39.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:133:0) after 1m0s
[13:06:39.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:133:0)
[13:06:39.892] Timer tick, asking Buildbucket for the build status
[13:06:39.926] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:39.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:134:0) after 1m0s
[13:07:39.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:134:0)
[13:07:39.905] Timer tick, asking Buildbucket for the build status
[13:07:39.961] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:39.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:135:0) after 1m0s
[13:08:39.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:135:0)
[13:08:39.960] Timer tick, asking Buildbucket for the build status
[13:08:40.011] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:40.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:136:0) after 1m0s
[13:09:40.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:136:0)
[13:09:40.037] Timer tick, asking Buildbucket for the build status
[13:09:40.124] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:40.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:137:0) after 1m0s
[13:10:40.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:137:0)
[13:10:40.148] Timer tick, asking Buildbucket for the build status
[13:10:40.213] Build 8918924994121591872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:40.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084355056063424080:138:0) after 1m0s
[13:11:32.237] Received PubSub notification, asking Buildbucket for the build status
[13:11:32.324] Build 8918924994121591872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:11:32.324] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1552655491320731",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552647325360905",
"id": "8918924994121591872",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3734.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\": [\"4201-b04f7f8\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"build-from-tarball-0-0e2c\"], \"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}, \"version\": \"75.0.3734.0\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-0e2c\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552647362672719",
"status": "COMPLETED",
"status_changed_ts": "1552655491776414",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084355056063424080",
"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/8918924994121591872/+/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:4399cf8ffe417810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552655491824761",
"url": "https://ci.chromium.org/b/8918924994121591872",
"utcnow_ts": "1552655492312026"
}
}
[13:11:32.324] Invocation finished in 2h16m8.199626175s with status SUCCEEDED