[23:53:56.735] New invocation is queued and will start shortly
[23:53:56.735] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:53:57.861] Starting the invocation (attempt 1)
[23:53:57.969] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:53:57.969] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:53:57.969] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9041272515319878192",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4190.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"1c4d4f3e-8175-475d-9c9e-bd7d70e7336f\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4190.3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9041272515319878192",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[23:53:58.553] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1593734038116792",
"id": "8875842453206224112",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4190.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"1c4d4f3e-8175-475d-9c9e-bd7d70e7336f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4190.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1593734038399100",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9041272515319878192",
"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/8875842453206224112/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1593734038399209",
"url": "https://ci.chromium.org/b/8875842453206224112",
"utcnow_ts": "1593734038545449"
}
}
[23:53:58.553] Task URL: https://ci.chromium.org/b/8875842453206224112
[23:53:58.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:2:0) after 1m0s
[23:54:58.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:2:0)
[23:54:58.583] Timer tick, asking Buildbucket for the build status
[23:54:58.715] Build 8875842453206224112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:58.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:3:0) after 1m0s
[23:55:58.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:3:0)
[23:55:58.809] Timer tick, asking Buildbucket for the build status
[23:55:58.927] Build 8875842453206224112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:58.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:4:0) after 1m0s
[23:56:58.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:4:0)
[23:56:58.943] Timer tick, asking Buildbucket for the build status
[23:56:59.547] Build 8875842453206224112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:59.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:5:0) after 1m0s
[23:57:59.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:5:0)
[23:57:59.611] Timer tick, asking Buildbucket for the build status
[23:57:59.794] Build 8875842453206224112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:59.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:6:0) after 1m0s
[23:58:04.152] Received PubSub notification, asking Buildbucket for the build status
[23:58:04.452] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:6:0)
[23:58:59.851] Timer tick, asking Buildbucket for the build status
[23:58:59.972] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:8:0) after 1m0s
[00:00:00.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:8:0)
[00:00:00.015] Timer tick, asking Buildbucket for the build status
[00:00:00.287] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:00.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:9:0) after 1m0s
[00:01:00.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:9:0)
[00:01:00.375] Timer tick, asking Buildbucket for the build status
[00:01:00.531] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:00.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:10:0) after 1m0s
[00:02:00.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:10:0)
[00:02:00.760] Timer tick, asking Buildbucket for the build status
[00:02:00.934] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:00.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:11:0) after 1m0s
[00:03:01.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:11:0)
[00:03:01.242] Timer tick, asking Buildbucket for the build status
[00:03:02.052] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:02.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:12:0) after 1m0s
[00:04:02.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:12:0)
[00:04:02.106] Timer tick, asking Buildbucket for the build status
[00:04:02.228] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:02.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:13:0) after 1m0s
[00:05:02.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:13:0)
[00:05:02.373] Timer tick, asking Buildbucket for the build status
[00:05:02.533] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:02.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:14:0) after 1m0s
[00:06:02.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:14:0)
[00:06:02.588] Timer tick, asking Buildbucket for the build status
[00:06:02.716] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:02.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:15:0) after 1m0s
[00:07:02.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:15:0)
[00:07:02.750] Timer tick, asking Buildbucket for the build status
[00:07:02.983] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:02.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:16:0) after 1m0s
[00:08:03.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:16:0)
[00:08:03.162] Timer tick, asking Buildbucket for the build status
[00:08:03.942] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:03.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:17:0) after 1m0s
[00:09:03.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:17:0)
[00:09:03.962] Timer tick, asking Buildbucket for the build status
[00:09:04.123] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:04.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:18:0) after 1m0s
[00:10:04.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:18:0)
[00:10:04.156] Timer tick, asking Buildbucket for the build status
[00:10:04.337] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:04.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:19:0) after 1m0s
[00:11:04.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:19:0)
[00:11:04.428] Timer tick, asking Buildbucket for the build status
[00:11:04.718] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:04.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:20:0) after 1m0s
[00:12:04.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:20:0)
[00:12:04.834] Timer tick, asking Buildbucket for the build status
[00:12:05.017] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:05.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:21:0) after 1m0s
[00:13:05.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:21:0)
[00:13:05.069] Timer tick, asking Buildbucket for the build status
[00:13:05.217] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:05.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:22:0) after 1m0s
[00:14:05.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:22:0)
[00:14:05.292] Timer tick, asking Buildbucket for the build status
[00:14:05.666] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:05.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:23:0) after 1m0s
[00:15:05.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:23:0)
[00:15:05.793] Timer tick, asking Buildbucket for the build status
[00:15:05.912] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:05.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:24:0) after 1m0s
[00:16:05.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:24:0)
[00:16:05.935] Timer tick, asking Buildbucket for the build status
[00:16:06.069] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:06.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:25:0) after 1m0s
[00:17:06.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:25:0)
[00:17:06.182] Timer tick, asking Buildbucket for the build status
[00:17:06.314] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:06.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:26:0) after 1m0s
[00:18:06.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:26:0)
[00:18:06.409] Timer tick, asking Buildbucket for the build status
[00:18:06.562] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:06.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:27:0) after 1m0s
[00:19:06.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:27:0)
[00:19:06.805] Timer tick, asking Buildbucket for the build status
[00:19:07.014] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:07.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:28:0) after 1m0s
[00:20:07.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:28:0)
[00:20:07.151] Timer tick, asking Buildbucket for the build status
[00:20:07.272] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:07.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:29:0) after 1m0s
[00:21:07.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:29:0)
[00:21:07.375] Timer tick, asking Buildbucket for the build status
[00:21:07.789] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:07.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:30:0) after 1m0s
[00:22:07.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:30:0)
[00:22:07.893] Timer tick, asking Buildbucket for the build status
[00:22:08.038] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:08.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:31:0) after 1m0s
[00:23:08.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:31:0)
[00:23:08.028] Timer tick, asking Buildbucket for the build status
[00:23:08.242] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:08.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:32:0) after 1m0s
[00:24:08.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:32:0)
[00:24:08.265] Timer tick, asking Buildbucket for the build status
[00:24:08.379] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:08.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:33:0) after 1m0s
[00:25:08.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:33:0)
[00:25:08.408] Timer tick, asking Buildbucket for the build status
[00:25:09.709] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:09.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:34:0) after 1m0s
[00:26:09.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:34:0)
[00:26:09.722] Timer tick, asking Buildbucket for the build status
[00:26:09.852] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:09.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:35:0) after 1m0s
[00:27:09.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:35:0)
[00:27:09.973] Timer tick, asking Buildbucket for the build status
[00:27:10.121] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:10.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:36:0) after 1m0s
[00:28:10.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:36:0)
[00:28:10.312] Timer tick, asking Buildbucket for the build status
[00:28:10.432] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:10.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:37:0) after 1m0s
[00:29:10.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:37:0)
[00:29:10.495] Timer tick, asking Buildbucket for the build status
[00:29:10.770] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:10.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:38:0) after 1m0s
[00:30:10.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:38:0)
[00:30:10.813] Timer tick, asking Buildbucket for the build status
[00:30:12.437] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:12.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:39:0) after 1m0s
[00:31:12.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:39:0)
[00:31:12.459] Timer tick, asking Buildbucket for the build status
[00:31:12.571] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:12.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:40:0) after 1m0s
[00:32:12.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:40:0)
[00:32:12.707] Timer tick, asking Buildbucket for the build status
[00:32:13.042] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:13.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:41:0) after 1m0s
[00:33:13.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:41:0)
[00:33:13.064] Timer tick, asking Buildbucket for the build status
[00:33:13.215] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:13.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:42:0) after 1m0s
[00:34:13.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:42:0)
[00:34:13.268] Timer tick, asking Buildbucket for the build status
[00:34:13.410] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:13.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:43:0) after 1m0s
[00:35:13.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:43:0)
[00:35:13.521] Timer tick, asking Buildbucket for the build status
[00:35:13.654] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:13.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:44:0) after 1m0s
[00:36:13.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:44:0)
[00:36:13.790] Timer tick, asking Buildbucket for the build status
[00:36:14.438] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:14.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:45:0) after 1m0s
[00:37:14.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:45:0)
[00:37:14.523] Timer tick, asking Buildbucket for the build status
[00:37:14.924] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:14.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:46:0) after 1m0s
[00:38:14.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:46:0)
[00:38:14.997] Timer tick, asking Buildbucket for the build status
[00:38:15.191] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:15.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:47:0) after 1m0s
[00:39:15.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:47:0)
[00:39:15.242] Timer tick, asking Buildbucket for the build status
[00:39:15.597] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:15.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:48:0) after 1m0s
[00:40:15.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:48:0)
[00:40:15.827] Timer tick, asking Buildbucket for the build status
[00:40:15.978] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:15.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:49:0) after 1m0s
[00:41:16.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:49:0)
[00:41:16.111] Timer tick, asking Buildbucket for the build status
[00:41:16.213] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:16.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:50:0) after 1m0s
[00:42:16.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:50:0)
[00:42:16.360] Timer tick, asking Buildbucket for the build status
[00:42:16.489] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:16.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:51:0) after 1m0s
[00:43:16.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:51:0)
[00:43:16.672] Timer tick, asking Buildbucket for the build status
[00:43:16.807] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:16.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:52:0) after 1m0s
[00:44:16.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:52:0)
[00:44:16.889] Timer tick, asking Buildbucket for the build status
[00:44:17.006] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:17.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:53:0) after 1m0s
[00:45:17.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:53:0)
[00:45:17.072] Timer tick, asking Buildbucket for the build status
[00:45:17.371] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:17.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:54:0) after 1m0s
[00:46:18.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:54:0)
[00:46:18.231] Timer tick, asking Buildbucket for the build status
[00:46:18.479] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:18.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:55:0) after 1m0s
[00:47:18.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:55:0)
[00:47:18.548] Timer tick, asking Buildbucket for the build status
[00:47:18.865] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:18.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:56:0) after 1m0s
[00:48:18.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:56:0)
[00:48:18.891] Timer tick, asking Buildbucket for the build status
[00:48:19.194] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:19.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:57:0) after 1m0s
[00:49:19.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:57:0)
[00:49:19.223] Timer tick, asking Buildbucket for the build status
[00:49:19.330] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:19.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:58:0) after 1m0s
[00:50:19.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:58:0)
[00:50:19.388] Timer tick, asking Buildbucket for the build status
[00:50:20.083] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:20.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:59:0) after 1m0s
[00:51:20.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:59:0)
[00:51:20.184] Timer tick, asking Buildbucket for the build status
[00:51:20.306] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:20.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:60:0) after 1m0s
[00:52:20.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:60:0)
[00:52:20.317] Timer tick, asking Buildbucket for the build status
[00:52:20.546] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:20.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:61:0) after 1m0s
[00:53:20.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:61:0)
[00:53:20.586] Timer tick, asking Buildbucket for the build status
[00:53:20.722] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:20.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:62:0) after 1m0s
[00:54:20.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:62:0)
[00:54:20.720] Timer tick, asking Buildbucket for the build status
[00:54:20.864] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:20.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:63:0) after 1m0s
[00:55:20.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:63:0)
[00:55:20.895] Timer tick, asking Buildbucket for the build status
[00:55:21.053] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:21.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:64:0) after 1m0s
[00:56:21.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:64:0)
[00:56:21.139] Timer tick, asking Buildbucket for the build status
[00:56:21.273] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:21.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:65:0) after 1m0s
[00:57:21.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:65:0)
[00:57:21.297] Timer tick, asking Buildbucket for the build status
[00:57:21.421] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:21.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:66:0) after 1m0s
[00:58:21.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:66:0)
[00:58:21.479] Timer tick, asking Buildbucket for the build status
[00:58:21.663] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:21.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:67:0) after 1m0s
[00:59:21.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:67:0)
[00:59:21.703] Timer tick, asking Buildbucket for the build status
[00:59:21.853] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:21.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:68:0) after 1m0s
[01:00:21.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:68:0)
[01:00:21.926] Timer tick, asking Buildbucket for the build status
[01:00:22.130] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:22.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:69:0) after 1m0s
[01:01:22.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:69:0)
[01:01:22.181] Timer tick, asking Buildbucket for the build status
[01:01:22.338] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:22.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:70:0) after 1m0s
[01:02:22.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:70:0)
[01:02:22.397] Timer tick, asking Buildbucket for the build status
[01:02:22.799] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:22.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:71:0) after 1m0s
[01:03:22.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:71:0)
[01:03:22.919] Timer tick, asking Buildbucket for the build status
[01:03:23.220] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:23.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:72:0) after 1m0s
[01:04:23.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:72:0)
[01:04:23.282] Timer tick, asking Buildbucket for the build status
[01:04:23.443] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:23.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:73:0) after 1m0s
[01:05:23.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:73:0)
[01:05:23.455] Timer tick, asking Buildbucket for the build status
[01:05:23.626] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:23.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:74:0) after 1m0s
[01:06:23.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:74:0)
[01:06:23.691] Timer tick, asking Buildbucket for the build status
[01:06:23.800] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:23.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:75:0) after 1m0s
[01:07:23.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:75:0)
[01:07:23.817] Timer tick, asking Buildbucket for the build status
[01:07:24.022] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:24.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:76:0) after 1m0s
[01:08:24.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:76:0)
[01:08:24.226] Timer tick, asking Buildbucket for the build status
[01:08:24.364] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:24.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:77:0) after 1m0s
[01:09:24.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:77:0)
[01:09:24.384] Timer tick, asking Buildbucket for the build status
[01:09:24.486] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:24.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:78:0) after 1m0s
[01:10:24.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:78:0)
[01:10:24.575] Timer tick, asking Buildbucket for the build status
[01:10:24.815] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:24.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:79:0) after 1m0s
[01:11:24.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:79:0)
[01:11:24.987] Timer tick, asking Buildbucket for the build status
[01:11:25.112] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:25.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:80:0) after 1m0s
[01:12:25.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:80:0)
[01:12:25.131] Timer tick, asking Buildbucket for the build status
[01:12:25.581] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:25.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:81:0) after 1m0s
[01:13:25.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:81:0)
[01:13:25.582] Timer tick, asking Buildbucket for the build status
[01:13:25.859] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:25.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:82:0) after 1m0s
[01:14:25.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:82:0)
[01:14:25.882] Timer tick, asking Buildbucket for the build status
[01:14:25.985] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:25.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:83:0) after 1m0s
[01:15:26.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:83:0)
[01:15:26.010] Timer tick, asking Buildbucket for the build status
[01:15:26.147] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:26.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:84:0) after 1m0s
[01:16:26.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:84:0)
[01:16:26.198] Timer tick, asking Buildbucket for the build status
[01:16:26.368] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:26.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:85:0) after 1m0s
[01:17:26.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:85:0)
[01:17:26.367] Timer tick, asking Buildbucket for the build status
[01:17:26.512] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:26.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:86:0) after 1m0s
[01:18:26.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:86:0)
[01:18:26.546] Timer tick, asking Buildbucket for the build status
[01:18:26.708] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:26.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:87:0) after 1m0s
[01:19:26.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:87:0)
[01:19:26.768] Timer tick, asking Buildbucket for the build status
[01:19:27.485] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:27.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:88:0) after 1m0s
[01:20:27.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:88:0)
[01:20:27.475] Timer tick, asking Buildbucket for the build status
[01:20:27.582] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:27.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:89:0) after 1m0s
[01:21:27.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:89:0)
[01:21:27.709] Timer tick, asking Buildbucket for the build status
[01:21:27.834] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:27.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:90:0) after 1m0s
[01:22:27.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:90:0)
[01:22:27.885] Timer tick, asking Buildbucket for the build status
[01:22:28.188] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:28.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:91:0) after 1m0s
[01:23:28.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:91:0)
[01:23:28.438] Timer tick, asking Buildbucket for the build status
[01:23:28.706] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:28.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:92:0) after 1m0s
[01:24:28.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:92:0)
[01:24:28.805] Timer tick, asking Buildbucket for the build status
[01:24:28.909] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:28.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:93:0) after 1m0s
[01:25:29.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:93:0)
[01:25:29.225] Timer tick, asking Buildbucket for the build status
[01:25:29.763] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:29.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:94:0) after 1m0s
[01:26:29.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:94:0)
[01:26:29.928] Timer tick, asking Buildbucket for the build status
[01:26:30.053] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:30.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:95:0) after 1m0s
[01:27:30.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:95:0)
[01:27:30.320] Timer tick, asking Buildbucket for the build status
[01:27:30.600] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:30.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:96:0) after 1m0s
[01:28:30.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:96:0)
[01:28:30.794] Timer tick, asking Buildbucket for the build status
[01:28:30.930] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:30.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:97:0) after 1m0s
[01:29:31.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:97:0)
[01:29:31.095] Timer tick, asking Buildbucket for the build status
[01:29:31.363] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:31.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:98:0) after 1m0s
[01:30:31.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:98:0)
[01:30:31.485] Timer tick, asking Buildbucket for the build status
[01:30:31.904] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:31.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:99:0) after 1m0s
[01:31:32.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:99:0)
[01:31:32.017] Timer tick, asking Buildbucket for the build status
[01:31:32.413] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:32.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:100:0) after 1m0s
[01:32:32.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:100:0)
[01:32:32.511] Timer tick, asking Buildbucket for the build status
[01:32:33.613] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:33.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:101:0) after 1m0s
[01:33:33.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:101:0)
[01:33:33.693] Timer tick, asking Buildbucket for the build status
[01:33:35.188] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:35.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:102:0) after 1m0s
[01:34:35.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:102:0)
[01:34:35.216] Timer tick, asking Buildbucket for the build status
[01:34:35.674] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:35.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:103:0) after 1m0s
[01:35:35.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:103:0)
[01:35:35.712] Timer tick, asking Buildbucket for the build status
[01:35:35.946] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:35.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:104:0) after 1m0s
[01:36:35.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:104:0)
[01:36:35.983] Timer tick, asking Buildbucket for the build status
[01:36:36.384] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:36.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:105:0) after 1m0s
[01:37:36.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:105:0)
[01:37:36.458] Timer tick, asking Buildbucket for the build status
[01:37:36.832] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:36.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:106:0) after 1m0s
[01:38:37.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:106:0)
[01:38:37.108] Timer tick, asking Buildbucket for the build status
[01:38:37.234] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:37.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:107:0) after 1m0s
[01:39:37.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:107:0)
[01:39:37.359] Timer tick, asking Buildbucket for the build status
[01:39:37.494] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:37.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:108:0) after 1m0s
[01:40:37.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:108:0)
[01:40:37.567] Timer tick, asking Buildbucket for the build status
[01:40:37.659] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:37.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:109:0) after 1m0s
[01:41:37.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:109:0)
[01:41:37.697] Timer tick, asking Buildbucket for the build status
[01:41:37.807] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:37.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:110:0) after 1m0s
[01:42:37.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:110:0)
[01:42:37.876] Timer tick, asking Buildbucket for the build status
[01:42:38.325] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:38.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:111:0) after 1m0s
[01:43:38.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:111:0)
[01:43:38.306] Timer tick, asking Buildbucket for the build status
[01:43:38.458] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:38.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:112:0) after 1m0s
[01:44:38.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:112:0)
[01:44:38.486] Timer tick, asking Buildbucket for the build status
[01:44:38.808] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:38.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:113:0) after 1m0s
[01:45:38.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:113:0)
[01:45:38.835] Timer tick, asking Buildbucket for the build status
[01:45:38.936] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:38.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:114:0) after 1m0s
[01:46:39.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:114:0)
[01:46:39.057] Timer tick, asking Buildbucket for the build status
[01:46:39.479] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:39.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:115:0) after 1m0s
[01:47:39.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:115:0)
[01:47:39.563] Timer tick, asking Buildbucket for the build status
[01:47:41.905] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:41.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:116:0) after 1m0s
[01:48:42.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:116:0)
[01:48:42.338] Timer tick, asking Buildbucket for the build status
[01:48:42.526] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:42.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:117:0) after 1m0s
[01:49:42.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:117:0)
[01:49:42.604] Timer tick, asking Buildbucket for the build status
[01:49:42.715] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:42.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:118:0) after 1m0s
[01:50:42.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:118:0)
[01:50:42.779] Timer tick, asking Buildbucket for the build status
[01:50:43.530] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:43.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:119:0) after 1m0s
[01:51:43.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:119:0)
[01:51:43.648] Timer tick, asking Buildbucket for the build status
[01:51:43.762] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:43.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:120:0) after 1m0s
[01:52:43.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:120:0)
[01:52:43.806] Timer tick, asking Buildbucket for the build status
[01:52:45.007] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:45.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:121:0) after 1m0s
[01:53:45.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:121:0)
[01:53:45.033] Timer tick, asking Buildbucket for the build status
[01:53:45.165] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:45.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:122:0) after 1m0s
[01:54:45.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:122:0)
[01:54:45.189] Timer tick, asking Buildbucket for the build status
[01:54:45.436] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:45.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:123:0) after 1m0s
[01:55:45.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:123:0)
[01:55:45.460] Timer tick, asking Buildbucket for the build status
[01:55:45.567] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:45.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:124:0) after 1m0s
[01:56:45.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:124:0)
[01:56:45.584] Timer tick, asking Buildbucket for the build status
[01:56:45.690] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:45.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:125:0) after 1m0s
[01:57:45.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:125:0)
[01:57:45.718] Timer tick, asking Buildbucket for the build status
[01:57:46.708] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:46.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:126:0) after 1m0s
[01:58:46.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:126:0)
[01:58:46.731] Timer tick, asking Buildbucket for the build status
[01:58:46.878] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:46.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:127:0) after 1m0s
[01:59:46.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:127:0)
[01:59:46.915] Timer tick, asking Buildbucket for the build status
[01:59:47.067] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:47.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:128:0) after 1m0s
[02:00:47.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:128:0)
[02:00:47.143] Timer tick, asking Buildbucket for the build status
[02:00:47.255] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:47.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:129:0) after 1m0s
[02:01:47.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:129:0)
[02:01:47.292] Timer tick, asking Buildbucket for the build status
[02:01:47.405] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:47.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:130:0) after 1m0s
[02:02:47.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:130:0)
[02:02:47.465] Timer tick, asking Buildbucket for the build status
[02:02:47.707] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:47.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:131:0) after 1m0s
[02:03:47.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:131:0)
[02:03:47.804] Timer tick, asking Buildbucket for the build status
[02:03:47.959] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:47.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:132:0) after 1m0s
[02:04:48.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:132:0)
[02:04:48.085] Timer tick, asking Buildbucket for the build status
[02:04:48.233] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:48.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:133:0) after 1m0s
[02:05:48.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:133:0)
[02:05:48.418] Timer tick, asking Buildbucket for the build status
[02:05:48.526] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:48.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:134:0) after 1m0s
[02:06:48.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:134:0)
[02:06:48.594] Timer tick, asking Buildbucket for the build status
[02:06:48.941] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:48.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:135:0) after 1m0s
[02:07:48.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:135:0)
[02:07:48.985] Timer tick, asking Buildbucket for the build status
[02:07:49.132] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:49.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:136:0) after 1m0s
[02:08:49.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:136:0)
[02:08:49.305] Timer tick, asking Buildbucket for the build status
[02:08:49.439] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:49.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:137:0) after 1m0s
[02:09:49.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:137:0)
[02:09:49.496] Timer tick, asking Buildbucket for the build status
[02:09:49.650] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:49.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:138:0) after 1m0s
[02:10:49.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:138:0)
[02:10:49.850] Timer tick, asking Buildbucket for the build status
[02:10:49.976] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:49.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:139:0) after 1m0s
[02:11:50.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:139:0)
[02:11:50.149] Timer tick, asking Buildbucket for the build status
[02:11:50.522] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:50.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:140:0) after 1m0s
[02:12:50.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:140:0)
[02:12:50.566] Timer tick, asking Buildbucket for the build status
[02:12:51.089] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:51.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:141:0) after 1m0s
[02:13:51.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:141:0)
[02:13:51.157] Timer tick, asking Buildbucket for the build status
[02:13:51.311] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:51.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:142:0) after 1m0s
[02:14:51.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:142:0)
[02:14:51.417] Timer tick, asking Buildbucket for the build status
[02:14:51.715] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:51.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:143:0) after 1m0s
[02:15:51.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:143:0)
[02:15:51.820] Timer tick, asking Buildbucket for the build status
[02:15:52.041] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:52.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:144:0) after 1m0s
[02:16:52.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:144:0)
[02:16:52.074] Timer tick, asking Buildbucket for the build status
[02:16:52.184] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:52.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:145:0) after 1m0s
[02:17:52.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:145:0)
[02:17:52.249] Timer tick, asking Buildbucket for the build status
[02:17:52.960] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:52.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:146:0) after 1m0s
[02:18:52.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:146:0)
[02:18:52.996] Timer tick, asking Buildbucket for the build status
[02:18:53.651] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:53.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:147:0) after 1m0s
[02:19:53.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:147:0)
[02:19:53.745] Timer tick, asking Buildbucket for the build status
[02:19:53.883] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:53.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:148:0) after 1m0s
[02:20:53.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:148:0)
[02:20:53.931] Timer tick, asking Buildbucket for the build status
[02:20:54.028] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:54.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:149:0) after 1m0s
[02:21:54.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:149:0)
[02:21:54.061] Timer tick, asking Buildbucket for the build status
[02:21:54.222] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:54.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:150:0) after 1m0s
[02:22:54.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:150:0)
[02:22:54.237] Timer tick, asking Buildbucket for the build status
[02:22:54.377] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:54.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:151:0) after 1m0s
[02:23:54.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:151:0)
[02:23:54.377] Timer tick, asking Buildbucket for the build status
[02:23:54.488] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:54.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:152:0) after 1m0s
[02:24:54.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:152:0)
[02:24:54.536] Timer tick, asking Buildbucket for the build status
[02:24:54.784] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:54.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:153:0) after 1m0s
[02:25:54.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:153:0)
[02:25:54.883] Timer tick, asking Buildbucket for the build status
[02:25:55.376] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:55.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:154:0) after 1m0s
[02:26:55.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:154:0)
[02:26:55.409] Timer tick, asking Buildbucket for the build status
[02:26:55.524] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:55.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:155:0) after 1m0s
[02:27:55.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:155:0)
[02:27:55.572] Timer tick, asking Buildbucket for the build status
[02:27:55.821] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:55.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:156:0) after 1m0s
[02:28:55.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:156:0)
[02:28:55.850] Timer tick, asking Buildbucket for the build status
[02:28:55.945] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:55.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:157:0) after 1m0s
[02:29:55.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:157:0)
[02:29:55.992] Timer tick, asking Buildbucket for the build status
[02:29:56.102] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:56.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:158:0) after 1m0s
[02:30:56.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:158:0)
[02:30:56.199] Timer tick, asking Buildbucket for the build status
[02:30:56.432] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:56.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:159:0) after 1m0s
[02:31:56.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:159:0)
[02:31:56.594] Timer tick, asking Buildbucket for the build status
[02:31:57.242] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:57.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:160:0) after 1m0s
[02:32:57.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:160:0)
[02:32:57.491] Timer tick, asking Buildbucket for the build status
[02:32:57.819] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:57.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:161:0) after 1m0s
[02:33:57.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:161:0)
[02:33:57.870] Timer tick, asking Buildbucket for the build status
[02:33:57.980] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:57.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:162:0) after 1m0s
[02:34:57.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:162:0)
[02:34:57.969] Timer tick, asking Buildbucket for the build status
[02:34:58.098] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:163:0) after 1m0s
[02:35:58.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:163:0)
[02:35:58.125] Timer tick, asking Buildbucket for the build status
[02:35:58.282] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:58.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:164:0) after 1m0s
[02:36:58.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:164:0)
[02:36:58.342] Timer tick, asking Buildbucket for the build status
[02:36:58.465] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:58.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:165:0) after 1m0s
[02:37:58.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:165:0)
[02:37:58.489] Timer tick, asking Buildbucket for the build status
[02:37:58.642] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:58.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:166:0) after 1m0s
[02:38:58.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:166:0)
[02:38:58.668] Timer tick, asking Buildbucket for the build status
[02:38:58.769] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:58.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:167:0) after 1m0s
[02:39:58.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:167:0)
[02:39:58.851] Timer tick, asking Buildbucket for the build status
[02:39:58.980] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:58.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:168:0) after 1m0s
[02:40:59.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:168:0)
[02:40:59.017] Timer tick, asking Buildbucket for the build status
[02:40:59.196] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:59.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:169:0) after 1m0s
[02:41:59.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:169:0)
[02:41:59.273] Timer tick, asking Buildbucket for the build status
[02:41:59.472] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:59.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:170:0) after 1m0s
[02:42:59.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:170:0)
[02:42:59.596] Timer tick, asking Buildbucket for the build status
[02:43:00.088] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:00.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:171:0) after 1m0s
[02:44:00.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:171:0)
[02:44:00.187] Timer tick, asking Buildbucket for the build status
[02:44:00.307] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:00.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:172:0) after 1m0s
[02:45:01.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:172:0)
[02:45:01.030] Timer tick, asking Buildbucket for the build status
[02:45:01.172] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:01.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:173:0) after 1m0s
[02:46:01.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:173:0)
[02:46:01.507] Timer tick, asking Buildbucket for the build status
[02:46:01.672] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:01.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:174:0) after 1m0s
[02:47:01.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:174:0)
[02:47:01.976] Timer tick, asking Buildbucket for the build status
[02:47:02.162] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:02.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:175:0) after 1m0s
[02:48:02.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:175:0)
[02:48:02.362] Timer tick, asking Buildbucket for the build status
[02:48:02.542] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:02.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:176:0) after 1m0s
[02:49:02.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:176:0)
[02:49:02.666] Timer tick, asking Buildbucket for the build status
[02:49:02.796] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:02.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:177:0) after 1m0s
[02:50:02.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:177:0)
[02:50:02.853] Timer tick, asking Buildbucket for the build status
[02:50:03.024] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:03.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:178:0) after 1m0s
[02:51:03.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:178:0)
[02:51:03.088] Timer tick, asking Buildbucket for the build status
[02:51:03.255] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:03.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:179:0) after 1m0s
[02:52:03.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:179:0)
[02:52:03.363] Timer tick, asking Buildbucket for the build status
[02:52:03.549] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:03.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:180:0) after 1m0s
[02:53:03.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:180:0)
[02:53:03.582] Timer tick, asking Buildbucket for the build status
[02:53:03.753] Build 8875842453206224112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:03.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041272515319878192:181:0) after 1m0s
[02:53:18.071] Received PubSub notification, asking Buildbucket for the build status
[02:53:18.212] Build 8875842453206224112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:53:18.212] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1593744796995458",
"created_by": "project:infra",
"created_ts": "1593734038116792",
"id": "8875842453206224112",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4190.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"1c4d4f3e-8175-475d-9c9e-bd7d70e7336f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4190.3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-45b8\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5284-0b74e14\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1593734283199877",
"status": "COMPLETED",
"status_changed_ts": "1593744797413531",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9041272515319878192",
"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/8875842453206224112/+/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:4d2ac50eacfc1810",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1593744797427800",
"url": "https://ci.chromium.org/b/8875842453206224112",
"utcnow_ts": "1593744798204125"
}
}
[02:53:18.212] Invocation finished in 2h59m21.494499669s with status SUCCEEDED