[12:56:20.113] New invocation is queued and will start shortly
[12:56:20.113] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:56:21.302] Starting the invocation (attempt 1)
[12:56:21.412] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:56:21.412] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:56:21.412] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083894462793217904",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3738.2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083894462793217904",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[12:56:22.259] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1553086581496727",
"id": "8918464400679216208",
"lease_expiration_ts": "1553205382047718",
"lease_key": "1263153258",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3738.2\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553086582051209",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083894462793217904",
"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/8918464400679216208/+/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:43b3fe140b7f3210",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553086582051338",
"url": "https://ci.chromium.org/b/8918464400679216208",
"utcnow_ts": "1553086582246099"
}
}
[12:56:22.259] Task URL: https://ci.chromium.org/b/8918464400679216208
[12:56:22.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:2:0) after 1m0s
[12:56:30.196] Received PubSub notification, asking Buildbucket for the build status
[12:56:30.361] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:22.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:2:0)
[12:57:22.276] Timer tick, asking Buildbucket for the build status
[12:57:22.403] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:22.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:4:0) after 1m0s
[12:58:22.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:4:0)
[12:58:22.438] Timer tick, asking Buildbucket for the build status
[12:58:22.514] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:22.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:5:0) after 1m0s
[12:59:22.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:5:0)
[12:59:22.605] Timer tick, asking Buildbucket for the build status
[12:59:22.700] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:22.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:6:0) after 1m0s
[13:00:22.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:6:0)
[13:00:22.719] Timer tick, asking Buildbucket for the build status
[13:00:22.776] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:22.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:7:0) after 1m0s
[13:01:22.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:7:0)
[13:01:22.792] Timer tick, asking Buildbucket for the build status
[13:01:22.836] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:22.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:8:0) after 1m0s
[13:02:22.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:8:0)
[13:02:22.823] Timer tick, asking Buildbucket for the build status
[13:02:22.890] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:22.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:9:0) after 1m0s
[13:03:22.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:9:0)
[13:03:22.893] Timer tick, asking Buildbucket for the build status
[13:03:23.214] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:23.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:10:0) after 1m0s
[13:04:23.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:10:0)
[13:04:23.220] Timer tick, asking Buildbucket for the build status
[13:04:23.319] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:23.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:11:0) after 1m0s
[13:05:23.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:11:0)
[13:05:23.335] Timer tick, asking Buildbucket for the build status
[13:05:23.585] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:23.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:12:0) after 1m0s
[13:06:23.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:12:0)
[13:06:23.611] Timer tick, asking Buildbucket for the build status
[13:06:23.682] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:23.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:13:0) after 1m0s
[13:07:23.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:13:0)
[13:07:23.676] Timer tick, asking Buildbucket for the build status
[13:07:23.758] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:23.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:14:0) after 1m0s
[13:08:23.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:14:0)
[13:08:23.781] Timer tick, asking Buildbucket for the build status
[13:08:23.897] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:23.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:15:0) after 1m0s
[13:09:23.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:15:0)
[13:09:23.881] Timer tick, asking Buildbucket for the build status
[13:09:23.977] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:16:0) after 1m0s
[13:10:24.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:16:0)
[13:10:24.027] Timer tick, asking Buildbucket for the build status
[13:10:24.103] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:24.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:17:0) after 1m0s
[13:11:24.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:17:0)
[13:11:24.136] Timer tick, asking Buildbucket for the build status
[13:11:24.195] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:24.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:18:0) after 1m0s
[13:12:24.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:18:0)
[13:12:24.213] Timer tick, asking Buildbucket for the build status
[13:12:24.249] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:24.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:19:0) after 1m0s
[13:13:24.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:19:0)
[13:13:24.243] Timer tick, asking Buildbucket for the build status
[13:13:24.286] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:24.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:20:0) after 1m0s
[13:14:24.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:20:0)
[13:14:24.327] Timer tick, asking Buildbucket for the build status
[13:14:24.371] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:24.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:21:0) after 1m0s
[13:15:24.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:21:0)
[13:15:24.359] Timer tick, asking Buildbucket for the build status
[13:15:24.502] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:24.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:22:0) after 1m0s
[13:16:24.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:22:0)
[13:16:24.520] Timer tick, asking Buildbucket for the build status
[13:16:24.811] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:24.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:23:0) after 1m0s
[13:17:24.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:23:0)
[13:17:24.831] Timer tick, asking Buildbucket for the build status
[13:17:24.905] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:24.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:24:0) after 1m0s
[13:18:24.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:24:0)
[13:18:24.918] Timer tick, asking Buildbucket for the build status
[13:18:24.990] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:25:0) after 1m0s
[13:19:24.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:25:0)
[13:19:24.991] Timer tick, asking Buildbucket for the build status
[13:19:25.089] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:25.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:26:0) after 1m0s
[13:20:25.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:26:0)
[13:20:25.076] Timer tick, asking Buildbucket for the build status
[13:20:25.204] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:25.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:27:0) after 1m0s
[13:21:25.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:27:0)
[13:21:25.188] Timer tick, asking Buildbucket for the build status
[13:21:25.513] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:25.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:28:0) after 1m0s
[13:22:25.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:28:0)
[13:22:25.529] Timer tick, asking Buildbucket for the build status
[13:22:25.670] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:25.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:29:0) after 1m0s
[13:23:25.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:29:0)
[13:23:25.688] Timer tick, asking Buildbucket for the build status
[13:23:25.813] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:25.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:30:0) after 1m0s
[13:24:25.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:30:0)
[13:24:25.821] Timer tick, asking Buildbucket for the build status
[13:24:25.914] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:25.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:31:0) after 1m0s
[13:25:25.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:31:0)
[13:25:25.929] Timer tick, asking Buildbucket for the build status
[13:25:25.992] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:25.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:32:0) after 1m0s
[13:26:26.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:32:0)
[13:26:26.014] Timer tick, asking Buildbucket for the build status
[13:26:26.071] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:26.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:33:0) after 1m0s
[13:27:26.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:33:0)
[13:27:26.079] Timer tick, asking Buildbucket for the build status
[13:27:26.171] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:26.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:34:0) after 1m0s
[13:28:26.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:34:0)
[13:28:26.187] Timer tick, asking Buildbucket for the build status
[13:28:26.225] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:35:0) after 1m0s
[13:29:26.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:35:0)
[13:29:26.233] Timer tick, asking Buildbucket for the build status
[13:29:26.307] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:36:0) after 1m0s
[13:30:26.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:36:0)
[13:30:26.291] Timer tick, asking Buildbucket for the build status
[13:30:26.366] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:37:0) after 1m0s
[13:31:26.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:37:0)
[13:31:26.363] Timer tick, asking Buildbucket for the build status
[13:31:26.570] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:38:0) after 1m0s
[13:32:26.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:38:0)
[13:32:26.595] Timer tick, asking Buildbucket for the build status
[13:32:26.669] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:26.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:39:0) after 1m0s
[13:33:26.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:39:0)
[13:33:26.690] Timer tick, asking Buildbucket for the build status
[13:33:26.775] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:26.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:40:0) after 1m0s
[13:34:26.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:40:0)
[13:34:26.800] Timer tick, asking Buildbucket for the build status
[13:34:26.872] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:41:0) after 1m0s
[13:35:26.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:41:0)
[13:35:26.859] Timer tick, asking Buildbucket for the build status
[13:35:27.017] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:27.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:42:0) after 1m0s
[13:36:27.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:42:0)
[13:36:27.023] Timer tick, asking Buildbucket for the build status
[13:36:27.066] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:27.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:43:0) after 1m0s
[13:37:27.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:43:0)
[13:37:27.098] Timer tick, asking Buildbucket for the build status
[13:37:27.151] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:27.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:44:0) after 1m0s
[13:38:27.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:44:0)
[13:38:27.171] Timer tick, asking Buildbucket for the build status
[13:38:27.210] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:45:0) after 1m0s
[13:39:27.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:45:0)
[13:39:27.223] Timer tick, asking Buildbucket for the build status
[13:39:27.365] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:46:0) after 1m0s
[13:40:27.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:46:0)
[13:40:27.393] Timer tick, asking Buildbucket for the build status
[13:40:27.460] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:27.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:47:0) after 1m0s
[13:41:27.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:47:0)
[13:41:27.479] Timer tick, asking Buildbucket for the build status
[13:41:27.758] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:27.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:48:0) after 1m0s
[13:42:27.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:48:0)
[13:42:27.798] Timer tick, asking Buildbucket for the build status
[13:42:27.974] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:27.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:49:0) after 1m0s
[13:43:28.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:49:0)
[13:43:28.002] Timer tick, asking Buildbucket for the build status
[13:43:28.198] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:28.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:50:0) after 1m0s
[13:44:28.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:50:0)
[13:44:28.204] Timer tick, asking Buildbucket for the build status
[13:44:28.267] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:28.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:51:0) after 1m0s
[13:45:28.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:51:0)
[13:45:28.312] Timer tick, asking Buildbucket for the build status
[13:45:28.571] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:28.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:52:0) after 1m0s
[13:46:28.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:52:0)
[13:46:28.575] Timer tick, asking Buildbucket for the build status
[13:46:28.707] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:28.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:53:0) after 1m0s
[13:47:28.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:53:0)
[13:47:28.773] Timer tick, asking Buildbucket for the build status
[13:47:28.921] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:28.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:54:0) after 1m0s
[13:48:28.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:54:0)
[13:48:28.920] Timer tick, asking Buildbucket for the build status
[13:48:29.100] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:29.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:55:0) after 1m0s
[13:49:29.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:55:0)
[13:49:29.097] Timer tick, asking Buildbucket for the build status
[13:49:29.209] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:29.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:56:0) after 1m0s
[13:50:29.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:56:0)
[13:50:29.234] Timer tick, asking Buildbucket for the build status
[13:50:29.310] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:29.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:57:0) after 1m0s
[13:51:29.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:57:0)
[13:51:29.279] Timer tick, asking Buildbucket for the build status
[13:51:29.475] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:29.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:58:0) after 1m0s
[13:52:29.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:58:0)
[13:52:29.496] Timer tick, asking Buildbucket for the build status
[13:52:29.531] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:29.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:59:0) after 1m0s
[13:53:29.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:59:0)
[13:53:29.553] Timer tick, asking Buildbucket for the build status
[13:53:29.627] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:29.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:60:0) after 1m0s
[13:54:29.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:60:0)
[13:54:29.645] Timer tick, asking Buildbucket for the build status
[13:54:29.704] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:29.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:61:0) after 1m0s
[13:55:29.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:61:0)
[13:55:29.725] Timer tick, asking Buildbucket for the build status
[13:55:29.786] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:29.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:62:0) after 1m0s
[13:56:29.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:62:0)
[13:56:29.797] Timer tick, asking Buildbucket for the build status
[13:56:29.849] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:29.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:63:0) after 1m0s
[13:57:29.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:63:0)
[13:57:29.870] Timer tick, asking Buildbucket for the build status
[13:57:29.955] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:29.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:64:0) after 1m0s
[13:58:29.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:64:0)
[13:58:29.935] Timer tick, asking Buildbucket for the build status
[13:58:29.992] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:29.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:65:0) after 1m0s
[13:59:29.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:65:0)
[13:59:29.990] Timer tick, asking Buildbucket for the build status
[13:59:30.048] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:30.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:66:0) after 1m0s
[14:00:30.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:66:0)
[14:00:30.103] Timer tick, asking Buildbucket for the build status
[14:00:30.159] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:30.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:67:0) after 1m0s
[14:01:30.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:67:0)
[14:01:30.194] Timer tick, asking Buildbucket for the build status
[14:01:30.257] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:30.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:68:0) after 1m0s
[14:02:30.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:68:0)
[14:02:30.273] Timer tick, asking Buildbucket for the build status
[14:02:30.324] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:30.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:69:0) after 1m0s
[14:03:30.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:69:0)
[14:03:30.344] Timer tick, asking Buildbucket for the build status
[14:03:30.409] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:30.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:70:0) after 1m0s
[14:04:30.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:70:0)
[14:04:30.413] Timer tick, asking Buildbucket for the build status
[14:04:30.658] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:30.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:71:0) after 1m0s
[14:05:30.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:71:0)
[14:05:30.682] Timer tick, asking Buildbucket for the build status
[14:05:30.802] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:30.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:72:0) after 1m0s
[14:06:30.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:72:0)
[14:06:30.800] Timer tick, asking Buildbucket for the build status
[14:06:31.072] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:31.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:73:0) after 1m0s
[14:07:31.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:73:0)
[14:07:31.046] Timer tick, asking Buildbucket for the build status
[14:07:31.092] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:31.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:74:0) after 1m0s
[14:08:31.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:74:0)
[14:08:31.085] Timer tick, asking Buildbucket for the build status
[14:08:31.288] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:31.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:75:0) after 1m0s
[14:09:31.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:75:0)
[14:09:31.264] Timer tick, asking Buildbucket for the build status
[14:09:31.318] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:31.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:76:0) after 1m0s
[14:10:31.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:76:0)
[14:10:31.328] Timer tick, asking Buildbucket for the build status
[14:10:31.454] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:31.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:77:0) after 1m0s
[14:11:31.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:77:0)
[14:11:31.478] Timer tick, asking Buildbucket for the build status
[14:11:31.522] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:31.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:78:0) after 1m0s
[14:12:31.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:78:0)
[14:12:31.513] Timer tick, asking Buildbucket for the build status
[14:12:31.665] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:31.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:79:0) after 1m0s
[14:13:31.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:79:0)
[14:13:31.686] Timer tick, asking Buildbucket for the build status
[14:13:31.790] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:31.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:80:0) after 1m0s
[14:14:31.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:80:0)
[14:14:31.782] Timer tick, asking Buildbucket for the build status
[14:14:31.849] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:31.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:81:0) after 1m0s
[14:15:31.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:81:0)
[14:15:31.846] Timer tick, asking Buildbucket for the build status
[14:15:31.907] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:31.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:82:0) after 1m0s
[14:16:31.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:82:0)
[14:16:31.897] Timer tick, asking Buildbucket for the build status
[14:16:32.111] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:32.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:83:0) after 1m0s
[14:17:32.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:83:0)
[14:17:32.097] Timer tick, asking Buildbucket for the build status
[14:17:32.163] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:32.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:84:0) after 1m0s
[14:18:32.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:84:0)
[14:18:32.159] Timer tick, asking Buildbucket for the build status
[14:18:32.201] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:32.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:85:0) after 1m0s
[14:19:32.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:85:0)
[14:19:32.178] Timer tick, asking Buildbucket for the build status
[14:19:32.245] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:32.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:86:0) after 1m0s
[14:20:32.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:86:0)
[14:20:32.268] Timer tick, asking Buildbucket for the build status
[14:20:32.394] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:32.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:87:0) after 1m0s
[14:21:32.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:87:0)
[14:21:32.393] Timer tick, asking Buildbucket for the build status
[14:21:32.531] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:32.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:88:0) after 1m0s
[14:22:32.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:88:0)
[14:22:32.536] Timer tick, asking Buildbucket for the build status
[14:22:32.598] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:32.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:89:0) after 1m0s
[14:23:32.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:89:0)
[14:23:32.576] Timer tick, asking Buildbucket for the build status
[14:23:32.619] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:90:0) after 1m0s
[14:24:32.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:90:0)
[14:24:32.640] Timer tick, asking Buildbucket for the build status
[14:24:32.688] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:32.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:91:0) after 1m0s
[14:25:32.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:91:0)
[14:25:32.694] Timer tick, asking Buildbucket for the build status
[14:25:32.762] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:92:0) after 1m0s
[14:26:32.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:92:0)
[14:26:32.783] Timer tick, asking Buildbucket for the build status
[14:26:32.899] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:32.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:93:0) after 1m0s
[14:27:32.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:93:0)
[14:27:32.921] Timer tick, asking Buildbucket for the build status
[14:27:32.980] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:32.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:94:0) after 1m0s
[14:28:33.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:94:0)
[14:28:33.003] Timer tick, asking Buildbucket for the build status
[14:28:33.047] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:33.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:95:0) after 1m0s
[14:29:33.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:95:0)
[14:29:33.049] Timer tick, asking Buildbucket for the build status
[14:29:33.998] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:96:0) after 1m0s
[14:30:34.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:96:0)
[14:30:34.011] Timer tick, asking Buildbucket for the build status
[14:30:34.072] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:34.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:97:0) after 1m0s
[14:31:34.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:97:0)
[14:31:34.100] Timer tick, asking Buildbucket for the build status
[14:31:34.328] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:34.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:98:0) after 1m0s
[14:32:34.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:98:0)
[14:32:34.348] Timer tick, asking Buildbucket for the build status
[14:32:34.414] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:34.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:99:0) after 1m0s
[14:33:34.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:99:0)
[14:33:34.427] Timer tick, asking Buildbucket for the build status
[14:33:34.464] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:34.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:100:0) after 1m0s
[14:34:34.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:100:0)
[14:34:34.489] Timer tick, asking Buildbucket for the build status
[14:34:34.561] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:34.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:101:0) after 1m0s
[14:35:34.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:101:0)
[14:35:34.584] Timer tick, asking Buildbucket for the build status
[14:35:34.842] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:34.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:102:0) after 1m0s
[14:36:34.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:102:0)
[14:36:34.867] Timer tick, asking Buildbucket for the build status
[14:36:34.927] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:34.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:103:0) after 1m0s
[14:37:34.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:103:0)
[14:37:34.992] Timer tick, asking Buildbucket for the build status
[14:37:35.154] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:35.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:104:0) after 1m0s
[14:38:35.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:104:0)
[14:38:35.195] Timer tick, asking Buildbucket for the build status
[14:38:35.323] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:35.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:105:0) after 1m0s
[14:39:35.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:105:0)
[14:39:35.344] Timer tick, asking Buildbucket for the build status
[14:39:35.616] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:35.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:106:0) after 1m0s
[14:40:35.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:106:0)
[14:40:35.672] Timer tick, asking Buildbucket for the build status
[14:40:35.792] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:35.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:107:0) after 1m0s
[14:41:35.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:107:0)
[14:41:35.824] Timer tick, asking Buildbucket for the build status
[14:41:35.870] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:35.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:108:0) after 1m0s
[14:42:35.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:108:0)
[14:42:35.886] Timer tick, asking Buildbucket for the build status
[14:42:35.981] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:35.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:109:0) after 1m0s
[14:43:35.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:109:0)
[14:43:35.978] Timer tick, asking Buildbucket for the build status
[14:43:36.021] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:36.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:110:0) after 1m0s
[14:44:36.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:110:0)
[14:44:36.076] Timer tick, asking Buildbucket for the build status
[14:44:36.150] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:36.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:111:0) after 1m0s
[14:45:36.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:111:0)
[14:45:36.174] Timer tick, asking Buildbucket for the build status
[14:45:36.282] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:36.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:112:0) after 1m0s
[14:46:36.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:112:0)
[14:46:36.340] Timer tick, asking Buildbucket for the build status
[14:46:36.572] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:36.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:113:0) after 1m0s
[14:47:36.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:113:0)
[14:47:36.601] Timer tick, asking Buildbucket for the build status
[14:47:36.661] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:36.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:114:0) after 1m0s
[14:48:36.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:114:0)
[14:48:36.641] Timer tick, asking Buildbucket for the build status
[14:48:36.778] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:36.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:115:0) after 1m0s
[14:49:36.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:115:0)
[14:49:36.778] Timer tick, asking Buildbucket for the build status
[14:49:36.820] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:36.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:116:0) after 1m0s
[14:50:36.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:116:0)
[14:50:36.798] Timer tick, asking Buildbucket for the build status
[14:50:36.855] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:36.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:117:0) after 1m0s
[14:51:36.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:117:0)
[14:51:36.837] Timer tick, asking Buildbucket for the build status
[14:51:36.896] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:36.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:118:0) after 1m0s
[14:52:36.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:118:0)
[14:52:36.893] Timer tick, asking Buildbucket for the build status
[14:52:36.991] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:36.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:119:0) after 1m0s
[14:53:36.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:119:0)
[14:53:36.991] Timer tick, asking Buildbucket for the build status
[14:53:37.249] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:37.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:120:0) after 1m0s
[14:54:37.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:120:0)
[14:54:37.238] Timer tick, asking Buildbucket for the build status
[14:54:37.291] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:37.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:121:0) after 1m0s
[14:55:37.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:121:0)
[14:55:37.304] Timer tick, asking Buildbucket for the build status
[14:55:37.348] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:37.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:122:0) after 1m0s
[14:56:37.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:122:0)
[14:56:37.387] Timer tick, asking Buildbucket for the build status
[14:56:37.508] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:37.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:123:0) after 1m0s
[14:57:37.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:123:0)
[14:57:37.501] Timer tick, asking Buildbucket for the build status
[14:57:37.589] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:37.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:124:0) after 1m0s
[14:58:37.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:124:0)
[14:58:37.584] Timer tick, asking Buildbucket for the build status
[14:58:37.737] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:37.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:125:0) after 1m0s
[14:59:37.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:125:0)
[14:59:37.764] Timer tick, asking Buildbucket for the build status
[14:59:37.808] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:37.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:126:0) after 1m0s
[15:00:37.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:126:0)
[15:00:37.816] Timer tick, asking Buildbucket for the build status
[15:00:37.886] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:37.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:127:0) after 1m0s
[15:01:37.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:127:0)
[15:01:37.886] Timer tick, asking Buildbucket for the build status
[15:01:37.934] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:37.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:128:0) after 1m0s
[15:02:37.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:128:0)
[15:02:37.982] Timer tick, asking Buildbucket for the build status
[15:02:38.105] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:38.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:129:0) after 1m0s
[15:03:38.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:129:0)
[15:03:38.160] Timer tick, asking Buildbucket for the build status
[15:03:38.295] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:38.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:130:0) after 1m0s
[15:04:38.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:130:0)
[15:04:38.287] Timer tick, asking Buildbucket for the build status
[15:04:38.346] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:38.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:131:0) after 1m0s
[15:05:38.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:131:0)
[15:05:38.372] Timer tick, asking Buildbucket for the build status
[15:05:38.465] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:38.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:132:0) after 1m0s
[15:06:38.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:132:0)
[15:06:38.489] Timer tick, asking Buildbucket for the build status
[15:06:38.632] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:38.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:133:0) after 1m0s
[15:07:38.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:133:0)
[15:07:38.614] Timer tick, asking Buildbucket for the build status
[15:07:38.673] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:38.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:134:0) after 1m0s
[15:08:38.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:134:0)
[15:08:38.689] Timer tick, asking Buildbucket for the build status
[15:08:38.895] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:38.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:135:0) after 1m0s
[15:09:38.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:135:0)
[15:09:38.961] Timer tick, asking Buildbucket for the build status
[15:09:39.229] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:39.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:136:0) after 1m0s
[15:10:39.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:136:0)
[15:10:39.212] Timer tick, asking Buildbucket for the build status
[15:10:44.738] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:44.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:137:0) after 1m0s
[15:11:44.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:137:0)
[15:11:44.737] Timer tick, asking Buildbucket for the build status
[15:11:44.796] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:44.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:138:0) after 1m0s
[15:12:44.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:138:0)
[15:12:44.818] Timer tick, asking Buildbucket for the build status
[15:12:44.861] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:44.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:139:0) after 1m0s
[15:13:44.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:139:0)
[15:13:44.891] Timer tick, asking Buildbucket for the build status
[15:13:44.938] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:44.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:140:0) after 1m0s
[15:14:44.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:140:0)
[15:14:44.925] Timer tick, asking Buildbucket for the build status
[15:14:45.111] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:45.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:141:0) after 1m0s
[15:15:45.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:141:0)
[15:15:45.124] Timer tick, asking Buildbucket for the build status
[15:15:45.193] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:45.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:142:0) after 1m0s
[15:16:45.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:142:0)
[15:16:45.223] Timer tick, asking Buildbucket for the build status
[15:16:45.269] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:45.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:143:0) after 1m0s
[15:17:45.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:143:0)
[15:17:45.271] Timer tick, asking Buildbucket for the build status
[15:17:45.415] Build 8918464400679216208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:45.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083894462793217904:144:0) after 1m0s
[15:17:48.581] Received PubSub notification, asking Buildbucket for the build status
[15:17:48.817] Build 8918464400679216208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:17:48.817] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553095062986504",
"created_by": "project:infra",
"created_ts": "1553086581496727",
"id": "8918464400679216208",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3738.2\"}}",
"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\"], \"id\": [\"build-from-tarball-0-e710\"], \"server_version\": [\"4211-90dafba\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"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.3738.2\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-e710\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553086589569594",
"status": "COMPLETED",
"status_changed_ts": "1553095068229057",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083894462793217904",
"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/8918464400679216208/+/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:43b3fe140b7f3210",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553095068231510",
"url": "https://ci.chromium.org/b/8918464400679216208",
"utcnow_ts": "1553095068789846"
}
}
[15:17:48.817] Invocation finished in 2h21m28.72006604s with status SUCCEEDED