[01:57:56.135] New invocation is queued and will start shortly
[01:57:56.135] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:57:57.273] Starting the invocation (attempt 1)
[01:57:57.373] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:57:57.373] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:57:57.373] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9050052620313605104",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"81.0.4044.81\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"59ea4f52-2003-4e73-b3bd-28ea6822268b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"81.0.4044.81\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9050052620313605104",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[01:57:58.041] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1585360677525204",
"id": "8884622558162269248",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.81\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"59ea4f52-2003-4e73-b3bd-28ea6822268b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.81\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585360677806225",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9050052620313605104",
"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/8884622558162269248/+/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": "1585360677806380",
"url": "https://ci.chromium.org/b/8884622558162269248",
"utcnow_ts": "1585360678029901"
}
}
[01:57:58.041] Task URL: https://ci.chromium.org/b/8884622558162269248
[01:57:58.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:2:0) after 1m0s
[01:58:58.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:2:0)
[01:58:58.232] Timer tick, asking Buildbucket for the build status
[01:58:58.500] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:58.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:3:0) after 1m0s
[01:59:58.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:3:0)
[01:59:58.560] Timer tick, asking Buildbucket for the build status
[01:59:58.696] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:58.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:4:0) after 1m0s
[02:00:58.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:4:0)
[02:00:58.704] Timer tick, asking Buildbucket for the build status
[02:00:58.822] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:58.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:5:0) after 1m0s
[02:01:58.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:5:0)
[02:01:58.905] Timer tick, asking Buildbucket for the build status
[02:01:59.216] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:59.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:6:0) after 1m0s
[02:02:59.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:6:0)
[02:02:59.293] Timer tick, asking Buildbucket for the build status
[02:02:59.435] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:59.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:7:0) after 1m0s
[02:03:59.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:7:0)
[02:03:59.654] Timer tick, asking Buildbucket for the build status
[02:03:59.911] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:59.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:8:0) after 1m0s
[02:04:59.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:8:0)
[02:04:59.939] Timer tick, asking Buildbucket for the build status
[02:05:00.125] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:00.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:9:0) after 1m0s
[02:06:00.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:9:0)
[02:06:00.125] Timer tick, asking Buildbucket for the build status
[02:06:00.323] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:00.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:10:0) after 1m0s
[02:07:00.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:10:0)
[02:07:00.422] Timer tick, asking Buildbucket for the build status
[02:07:00.592] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:00.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:11:0) after 1m0s
[02:08:00.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:11:0)
[02:08:00.578] Timer tick, asking Buildbucket for the build status
[02:08:00.753] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:00.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:12:0) after 1m0s
[02:09:00.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:12:0)
[02:09:00.752] Timer tick, asking Buildbucket for the build status
[02:09:00.880] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:00.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:13:0) after 1m0s
[02:10:01.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:13:0)
[02:10:01.104] Timer tick, asking Buildbucket for the build status
[02:10:01.341] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:01.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:14:0) after 1m0s
[02:11:01.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:14:0)
[02:11:01.330] Timer tick, asking Buildbucket for the build status
[02:11:01.446] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:01.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:15:0) after 1m0s
[02:12:01.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:15:0)
[02:12:01.501] Timer tick, asking Buildbucket for the build status
[02:12:01.627] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:01.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:16:0) after 1m0s
[02:13:01.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:16:0)
[02:13:01.658] Timer tick, asking Buildbucket for the build status
[02:13:02.076] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:02.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:17:0) after 1m0s
[02:14:02.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:17:0)
[02:14:02.075] Timer tick, asking Buildbucket for the build status
[02:14:02.213] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:02.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:18:0) after 1m0s
[02:15:02.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:18:0)
[02:15:02.329] Timer tick, asking Buildbucket for the build status
[02:15:03.191] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:03.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:19:0) after 1m0s
[02:16:03.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:19:0)
[02:16:03.167] Timer tick, asking Buildbucket for the build status
[02:16:03.518] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:03.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:20:0) after 1m0s
[02:17:03.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:20:0)
[02:17:03.555] Timer tick, asking Buildbucket for the build status
[02:17:03.786] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:03.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:21:0) after 1m0s
[02:18:03.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:21:0)
[02:18:03.816] Timer tick, asking Buildbucket for the build status
[02:18:04.029] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:04.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:22:0) after 1m0s
[02:19:04.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:22:0)
[02:19:04.056] Timer tick, asking Buildbucket for the build status
[02:19:04.483] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:04.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:23:0) after 1m0s
[02:20:04.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:23:0)
[02:20:04.506] Timer tick, asking Buildbucket for the build status
[02:20:04.693] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:04.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:24:0) after 1m0s
[02:21:04.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:24:0)
[02:21:04.728] Timer tick, asking Buildbucket for the build status
[02:21:04.858] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:04.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:25:0) after 1m0s
[02:22:04.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:25:0)
[02:22:04.938] Timer tick, asking Buildbucket for the build status
[02:22:05.050] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:05.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:26:0) after 1m0s
[02:23:05.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:26:0)
[02:23:05.104] Timer tick, asking Buildbucket for the build status
[02:23:05.352] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:05.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:27:0) after 1m0s
[02:24:05.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:27:0)
[02:24:05.410] Timer tick, asking Buildbucket for the build status
[02:24:05.641] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:05.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:28:0) after 1m0s
[02:25:05.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:28:0)
[02:25:05.661] Timer tick, asking Buildbucket for the build status
[02:25:05.767] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:05.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:29:0) after 1m0s
[02:26:05.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:29:0)
[02:26:05.831] Timer tick, asking Buildbucket for the build status
[02:26:05.979] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:05.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:30:0) after 1m0s
[02:27:06.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:30:0)
[02:27:06.040] Timer tick, asking Buildbucket for the build status
[02:27:06.237] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:06.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:31:0) after 1m0s
[02:28:06.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:31:0)
[02:28:06.296] Timer tick, asking Buildbucket for the build status
[02:28:06.527] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:06.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:32:0) after 1m0s
[02:29:06.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:32:0)
[02:29:06.671] Timer tick, asking Buildbucket for the build status
[02:29:06.977] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:06.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:33:0) after 1m0s
[02:30:07.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:33:0)
[02:30:07.070] Timer tick, asking Buildbucket for the build status
[02:30:07.212] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:07.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:34:0) after 1m0s
[02:31:07.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:34:0)
[02:31:07.432] Timer tick, asking Buildbucket for the build status
[02:31:07.735] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:07.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:35:0) after 1m0s
[02:32:07.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:35:0)
[02:32:07.874] Timer tick, asking Buildbucket for the build status
[02:32:07.997] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:07.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:36:0) after 1m0s
[02:33:08.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:36:0)
[02:33:08.162] Timer tick, asking Buildbucket for the build status
[02:33:08.329] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:08.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:37:0) after 1m0s
[02:34:08.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:37:0)
[02:34:08.532] Timer tick, asking Buildbucket for the build status
[02:34:08.754] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:08.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:38:0) after 1m0s
[02:35:08.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:38:0)
[02:35:08.824] Timer tick, asking Buildbucket for the build status
[02:35:09.213] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:09.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:39:0) after 1m0s
[02:36:09.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:39:0)
[02:36:09.504] Timer tick, asking Buildbucket for the build status
[02:36:09.623] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:09.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:40:0) after 1m0s
[02:37:09.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:40:0)
[02:37:09.955] Timer tick, asking Buildbucket for the build status
[02:37:10.043] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:10.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:41:0) after 1m0s
[02:38:10.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:41:0)
[02:38:10.142] Timer tick, asking Buildbucket for the build status
[02:38:10.538] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:10.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:42:0) after 1m0s
[02:39:10.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:42:0)
[02:39:10.574] Timer tick, asking Buildbucket for the build status
[02:39:10.743] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:10.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:43:0) after 1m0s
[02:40:10.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:43:0)
[02:40:10.848] Timer tick, asking Buildbucket for the build status
[02:40:10.977] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:10.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:44:0) after 1m0s
[02:41:11.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:44:0)
[02:41:11.103] Timer tick, asking Buildbucket for the build status
[02:41:11.493] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:11.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:45:0) after 1m0s
[02:42:11.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:45:0)
[02:42:11.516] Timer tick, asking Buildbucket for the build status
[02:42:11.693] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:11.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:46:0) after 1m0s
[02:43:11.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:46:0)
[02:43:11.766] Timer tick, asking Buildbucket for the build status
[02:43:12.119] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:12.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:47:0) after 1m0s
[02:44:12.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:47:0)
[02:44:12.193] Timer tick, asking Buildbucket for the build status
[02:44:12.386] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:12.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:48:0) after 1m0s
[02:45:12.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:48:0)
[02:45:12.532] Timer tick, asking Buildbucket for the build status
[02:45:12.658] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:12.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:49:0) after 1m0s
[02:46:12.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:49:0)
[02:46:12.735] Timer tick, asking Buildbucket for the build status
[02:46:12.945] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:12.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:50:0) after 1m0s
[02:47:13.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:50:0)
[02:47:13.029] Timer tick, asking Buildbucket for the build status
[02:47:13.341] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:13.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:51:0) after 1m0s
[02:48:13.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:51:0)
[02:48:13.414] Timer tick, asking Buildbucket for the build status
[02:48:13.834] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:13.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:52:0) after 1m0s
[02:49:13.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:52:0)
[02:49:13.900] Timer tick, asking Buildbucket for the build status
[02:49:14.142] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:14.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:53:0) after 1m0s
[02:50:14.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:53:0)
[02:50:14.194] Timer tick, asking Buildbucket for the build status
[02:50:14.435] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:14.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:54:0) after 1m0s
[02:51:14.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:54:0)
[02:51:14.517] Timer tick, asking Buildbucket for the build status
[02:51:14.662] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:14.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:55:0) after 1m0s
[02:52:14.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:55:0)
[02:52:14.748] Timer tick, asking Buildbucket for the build status
[02:52:15.031] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:15.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:56:0) after 1m0s
[02:53:15.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:56:0)
[02:53:15.088] Timer tick, asking Buildbucket for the build status
[02:53:15.324] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:15.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:57:0) after 1m0s
[02:54:15.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:57:0)
[02:54:15.362] Timer tick, asking Buildbucket for the build status
[02:54:15.615] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:15.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:58:0) after 1m0s
[02:55:15.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:58:0)
[02:55:15.641] Timer tick, asking Buildbucket for the build status
[02:55:15.780] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:15.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:59:0) after 1m0s
[02:56:15.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:59:0)
[02:56:15.814] Timer tick, asking Buildbucket for the build status
[02:56:15.936] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:15.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:60:0) after 1m0s
[02:57:16.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:60:0)
[02:57:16.011] Timer tick, asking Buildbucket for the build status
[02:57:16.442] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:16.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:61:0) after 1m0s
[02:58:16.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:61:0)
[02:58:16.512] Timer tick, asking Buildbucket for the build status
[02:58:17.072] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:17.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:62:0) after 1m0s
[02:59:17.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:62:0)
[02:59:17.120] Timer tick, asking Buildbucket for the build status
[02:59:17.275] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:17.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:63:0) after 1m0s
[03:00:17.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:63:0)
[03:00:17.305] Timer tick, asking Buildbucket for the build status
[03:00:17.523] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:17.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:64:0) after 1m0s
[03:01:17.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:64:0)
[03:01:17.546] Timer tick, asking Buildbucket for the build status
[03:01:17.671] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:17.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:65:0) after 1m0s
[03:02:17.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:65:0)
[03:02:17.705] Timer tick, asking Buildbucket for the build status
[03:02:17.946] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:17.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:66:0) after 1m0s
[03:03:18.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:66:0)
[03:03:18.022] Timer tick, asking Buildbucket for the build status
[03:03:18.451] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:18.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:67:0) after 1m0s
[03:04:18.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:67:0)
[03:04:18.517] Timer tick, asking Buildbucket for the build status
[03:04:18.671] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:18.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:68:0) after 1m0s
[03:05:18.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:68:0)
[03:05:18.792] Timer tick, asking Buildbucket for the build status
[03:05:18.981] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:18.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:69:0) after 1m0s
[03:06:19.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:69:0)
[03:06:19.036] Timer tick, asking Buildbucket for the build status
[03:06:19.293] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:19.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:70:0) after 1m0s
[03:07:19.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:70:0)
[03:07:19.281] Timer tick, asking Buildbucket for the build status
[03:07:19.378] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:19.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:71:0) after 1m0s
[03:08:19.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:71:0)
[03:08:19.458] Timer tick, asking Buildbucket for the build status
[03:08:19.657] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:19.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:72:0) after 1m0s
[03:09:19.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:72:0)
[03:09:19.668] Timer tick, asking Buildbucket for the build status
[03:09:19.777] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:19.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:73:0) after 1m0s
[03:10:19.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:73:0)
[03:10:19.802] Timer tick, asking Buildbucket for the build status
[03:10:20.002] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:20.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:74:0) after 1m0s
[03:11:20.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:74:0)
[03:11:20.038] Timer tick, asking Buildbucket for the build status
[03:11:20.204] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:20.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:75:0) after 1m0s
[03:12:20.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:75:0)
[03:12:20.250] Timer tick, asking Buildbucket for the build status
[03:12:20.382] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:20.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:76:0) after 1m0s
[03:13:20.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:76:0)
[03:13:20.481] Timer tick, asking Buildbucket for the build status
[03:13:20.820] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:20.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:77:0) after 1m0s
[03:14:20.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:77:0)
[03:14:20.809] Timer tick, asking Buildbucket for the build status
[03:14:21.189] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:21.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:78:0) after 1m0s
[03:15:21.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:78:0)
[03:15:21.213] Timer tick, asking Buildbucket for the build status
[03:15:21.333] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:21.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:79:0) after 1m0s
[03:16:21.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:79:0)
[03:16:21.354] Timer tick, asking Buildbucket for the build status
[03:16:21.971] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:21.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:80:0) after 1m0s
[03:17:22.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:80:0)
[03:17:22.027] Timer tick, asking Buildbucket for the build status
[03:17:22.168] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:22.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:81:0) after 1m0s
[03:18:22.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:81:0)
[03:18:22.203] Timer tick, asking Buildbucket for the build status
[03:18:23.198] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:23.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:82:0) after 1m0s
[03:19:23.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:82:0)
[03:19:23.209] Timer tick, asking Buildbucket for the build status
[03:19:23.316] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:23.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:83:0) after 1m0s
[03:20:23.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:83:0)
[03:20:23.371] Timer tick, asking Buildbucket for the build status
[03:20:23.950] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:23.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:84:0) after 1m0s
[03:21:24.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:84:0)
[03:21:24.013] Timer tick, asking Buildbucket for the build status
[03:21:24.239] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:24.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:85:0) after 1m0s
[03:22:24.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:85:0)
[03:22:24.238] Timer tick, asking Buildbucket for the build status
[03:22:24.513] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:24.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:86:0) after 1m0s
[03:23:24.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:86:0)
[03:23:24.601] Timer tick, asking Buildbucket for the build status
[03:23:24.863] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:24.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:87:0) after 1m0s
[03:24:24.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:87:0)
[03:24:24.893] Timer tick, asking Buildbucket for the build status
[03:24:25.021] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:25.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:88:0) after 1m0s
[03:25:25.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:88:0)
[03:25:25.107] Timer tick, asking Buildbucket for the build status
[03:25:25.267] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:25.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:89:0) after 1m0s
[03:26:25.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:89:0)
[03:26:25.355] Timer tick, asking Buildbucket for the build status
[03:26:25.839] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:25.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:90:0) after 1m0s
[03:27:25.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:90:0)
[03:27:25.905] Timer tick, asking Buildbucket for the build status
[03:27:26.077] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:26.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:91:0) after 1m0s
[03:28:26.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:91:0)
[03:28:26.096] Timer tick, asking Buildbucket for the build status
[03:28:26.434] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:26.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:92:0) after 1m0s
[03:29:26.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:92:0)
[03:29:26.571] Timer tick, asking Buildbucket for the build status
[03:29:26.670] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:26.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:93:0) after 1m0s
[03:30:26.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:93:0)
[03:30:26.879] Timer tick, asking Buildbucket for the build status
[03:30:27.236] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:27.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:94:0) after 1m0s
[03:31:27.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:94:0)
[03:31:27.267] Timer tick, asking Buildbucket for the build status
[03:31:27.409] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:27.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:95:0) after 1m0s
[03:32:27.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:95:0)
[03:32:27.442] Timer tick, asking Buildbucket for the build status
[03:32:27.731] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:27.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:96:0) after 1m0s
[03:33:27.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:96:0)
[03:33:27.804] Timer tick, asking Buildbucket for the build status
[03:33:28.834] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:28.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:97:0) after 1m0s
[03:34:28.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:97:0)
[03:34:28.909] Timer tick, asking Buildbucket for the build status
[03:34:29.026] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:29.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:98:0) after 1m0s
[03:35:29.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:98:0)
[03:35:29.096] Timer tick, asking Buildbucket for the build status
[03:35:29.235] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:29.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:99:0) after 1m0s
[03:36:29.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:99:0)
[03:36:29.275] Timer tick, asking Buildbucket for the build status
[03:36:29.451] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:29.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:100:0) after 1m0s
[03:37:29.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:100:0)
[03:37:29.457] Timer tick, asking Buildbucket for the build status
[03:37:29.617] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:29.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:101:0) after 1m0s
[03:38:29.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:101:0)
[03:38:29.619] Timer tick, asking Buildbucket for the build status
[03:38:30.350] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:30.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:102:0) after 1m0s
[03:39:30.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:102:0)
[03:39:30.379] Timer tick, asking Buildbucket for the build status
[03:39:30.506] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:30.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:103:0) after 1m0s
[03:40:30.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:103:0)
[03:40:30.526] Timer tick, asking Buildbucket for the build status
[03:40:30.685] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:30.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:104:0) after 1m0s
[03:41:30.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:104:0)
[03:41:30.778] Timer tick, asking Buildbucket for the build status
[03:41:30.953] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:30.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:105:0) after 1m0s
[03:42:31.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:105:0)
[03:42:31.130] Timer tick, asking Buildbucket for the build status
[03:42:31.320] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:31.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:106:0) after 1m0s
[03:43:31.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:106:0)
[03:43:31.304] Timer tick, asking Buildbucket for the build status
[03:43:36.371] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:36.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:107:0) after 1m0s
[03:44:36.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:107:0)
[03:44:36.472] Timer tick, asking Buildbucket for the build status
[03:44:36.654] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:36.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:108:0) after 1m0s
[03:45:36.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:108:0)
[03:45:36.675] Timer tick, asking Buildbucket for the build status
[03:45:36.791] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:36.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:109:0) after 1m0s
[03:46:36.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:109:0)
[03:46:36.803] Timer tick, asking Buildbucket for the build status
[03:46:36.948] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:36.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:110:0) after 1m0s
[03:47:36.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:110:0)
[03:47:36.972] Timer tick, asking Buildbucket for the build status
[03:47:37.102] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:37.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:111:0) after 1m0s
[03:48:37.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:111:0)
[03:48:37.138] Timer tick, asking Buildbucket for the build status
[03:48:37.263] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:37.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:112:0) after 1m0s
[03:49:37.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:112:0)
[03:49:37.310] Timer tick, asking Buildbucket for the build status
[03:49:37.695] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:37.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:113:0) after 1m0s
[03:50:37.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:113:0)
[03:50:37.745] Timer tick, asking Buildbucket for the build status
[03:50:38.394] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:38.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:114:0) after 1m0s
[03:51:38.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:114:0)
[03:51:38.478] Timer tick, asking Buildbucket for the build status
[03:51:38.621] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:38.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:115:0) after 1m0s
[03:52:38.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:115:0)
[03:52:38.690] Timer tick, asking Buildbucket for the build status
[03:52:38.852] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:38.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:116:0) after 1m0s
[03:53:38.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:116:0)
[03:53:38.898] Timer tick, asking Buildbucket for the build status
[03:53:44.862] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:44.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:117:0) after 1m0s
[03:54:44.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:117:0)
[03:54:44.985] Timer tick, asking Buildbucket for the build status
[03:54:45.247] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:45.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:118:0) after 1m0s
[03:55:45.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:118:0)
[03:55:45.322] Timer tick, asking Buildbucket for the build status
[03:55:45.520] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:45.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:119:0) after 1m0s
[03:56:45.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:119:0)
[03:56:45.626] Timer tick, asking Buildbucket for the build status
[03:56:45.816] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:45.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:120:0) after 1m0s
[03:57:45.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:120:0)
[03:57:45.861] Timer tick, asking Buildbucket for the build status
[03:57:45.991] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:45.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:121:0) after 1m0s
[03:58:46.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:121:0)
[03:58:46.031] Timer tick, asking Buildbucket for the build status
[03:58:49.530] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:49.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:122:0) after 1m0s
[03:59:49.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:122:0)
[03:59:49.601] Timer tick, asking Buildbucket for the build status
[03:59:49.903] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:49.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:123:0) after 1m0s
[04:00:49.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:123:0)
[04:00:49.930] Timer tick, asking Buildbucket for the build status
[04:00:50.255] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:50.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:124:0) after 1m0s
[04:01:50.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:124:0)
[04:01:50.270] Timer tick, asking Buildbucket for the build status
[04:01:50.419] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:50.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:125:0) after 1m0s
[04:02:50.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:125:0)
[04:02:50.457] Timer tick, asking Buildbucket for the build status
[04:02:50.889] Build 8884622558162269248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:50.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:126:0) after 1m0s
[04:03:02.539] Received PubSub notification, asking Buildbucket for the build status
[04:03:02.700] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:50.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:126:0)
[04:03:50.899] Timer tick, asking Buildbucket for the build status
[04:03:51.177] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:51.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:128:0) after 1m0s
[04:04:51.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:128:0)
[04:04:51.202] Timer tick, asking Buildbucket for the build status
[04:04:51.431] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:51.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:129:0) after 1m0s
[04:05:51.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:129:0)
[04:05:51.406] Timer tick, asking Buildbucket for the build status
[04:05:51.559] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:51.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:130:0) after 1m0s
[04:06:51.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:130:0)
[04:06:51.597] Timer tick, asking Buildbucket for the build status
[04:06:51.705] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:51.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:131:0) after 1m0s
[04:07:51.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:131:0)
[04:07:51.754] Timer tick, asking Buildbucket for the build status
[04:07:51.983] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:51.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:132:0) after 1m0s
[04:08:52.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:132:0)
[04:08:52.015] Timer tick, asking Buildbucket for the build status
[04:08:52.479] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:52.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:133:0) after 1m0s
[04:09:52.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:133:0)
[04:09:52.604] Timer tick, asking Buildbucket for the build status
[04:09:52.745] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:52.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:134:0) after 1m0s
[04:10:52.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:134:0)
[04:10:52.810] Timer tick, asking Buildbucket for the build status
[04:10:52.923] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:52.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:135:0) after 1m0s
[04:11:52.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:135:0)
[04:11:52.962] Timer tick, asking Buildbucket for the build status
[04:11:53.104] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:53.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:136:0) after 1m0s
[04:12:53.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:136:0)
[04:12:53.150] Timer tick, asking Buildbucket for the build status
[04:12:53.466] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:53.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:137:0) after 1m0s
[04:13:53.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:137:0)
[04:13:53.618] Timer tick, asking Buildbucket for the build status
[04:13:54.152] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:54.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:138:0) after 1m0s
[04:14:54.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:138:0)
[04:14:54.280] Timer tick, asking Buildbucket for the build status
[04:14:54.462] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:54.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:139:0) after 1m0s
[04:15:54.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:139:0)
[04:15:54.539] Timer tick, asking Buildbucket for the build status
[04:15:54.658] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:54.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:140:0) after 1m0s
[04:16:54.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:140:0)
[04:16:54.723] Timer tick, asking Buildbucket for the build status
[04:16:54.957] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:54.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:141:0) after 1m0s
[04:17:55.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:141:0)
[04:17:55.020] Timer tick, asking Buildbucket for the build status
[04:17:55.600] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:55.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:142:0) after 1m0s
[04:18:55.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:142:0)
[04:18:55.637] Timer tick, asking Buildbucket for the build status
[04:18:55.770] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:55.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:143:0) after 1m0s
[04:19:56.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:143:0)
[04:19:56.156] Timer tick, asking Buildbucket for the build status
[04:19:56.415] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:56.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:144:0) after 1m0s
[04:20:56.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:144:0)
[04:20:56.502] Timer tick, asking Buildbucket for the build status
[04:20:56.879] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:145:0) after 1m0s
[04:21:56.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:145:0)
[04:21:56.957] Timer tick, asking Buildbucket for the build status
[04:21:57.376] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:57.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:146:0) after 1m0s
[04:22:57.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:146:0)
[04:22:57.544] Timer tick, asking Buildbucket for the build status
[04:22:57.678] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:57.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:147:0) after 1m0s
[04:23:57.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:147:0)
[04:23:57.788] Timer tick, asking Buildbucket for the build status
[04:23:57.920] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:57.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:148:0) after 1m0s
[04:24:58.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:148:0)
[04:24:58.111] Timer tick, asking Buildbucket for the build status
[04:24:58.316] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:58.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:149:0) after 1m0s
[04:25:58.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:149:0)
[04:25:58.548] Timer tick, asking Buildbucket for the build status
[04:25:58.733] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:58.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:150:0) after 1m0s
[04:26:58.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:150:0)
[04:26:58.821] Timer tick, asking Buildbucket for the build status
[04:26:58.988] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:58.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:151:0) after 1m0s
[04:27:59.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:151:0)
[04:27:59.100] Timer tick, asking Buildbucket for the build status
[04:27:59.221] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:59.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:152:0) after 1m0s
[04:28:59.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:152:0)
[04:28:59.309] Timer tick, asking Buildbucket for the build status
[04:28:59.458] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:59.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:153:0) after 1m0s
[04:29:59.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:153:0)
[04:29:59.629] Timer tick, asking Buildbucket for the build status
[04:30:00.083] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:00.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:154:0) after 1m0s
[04:31:00.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:154:0)
[04:31:00.211] Timer tick, asking Buildbucket for the build status
[04:31:00.414] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:00.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:155:0) after 1m0s
[04:32:00.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:155:0)
[04:32:00.549] Timer tick, asking Buildbucket for the build status
[04:32:00.728] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:00.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:156:0) after 1m0s
[04:33:00.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:156:0)
[04:33:00.734] Timer tick, asking Buildbucket for the build status
[04:33:02.610] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:02.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:157:0) after 1m0s
[04:34:02.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:157:0)
[04:34:02.743] Timer tick, asking Buildbucket for the build status
[04:34:03.277] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:03.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:158:0) after 1m0s
[04:35:03.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:158:0)
[04:35:03.296] Timer tick, asking Buildbucket for the build status
[04:35:03.459] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:03.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:159:0) after 1m0s
[04:36:03.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:159:0)
[04:36:03.905] Timer tick, asking Buildbucket for the build status
[04:36:04.038] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:04.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:160:0) after 1m0s
[04:37:04.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:160:0)
[04:37:04.393] Timer tick, asking Buildbucket for the build status
[04:37:04.525] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:04.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:161:0) after 1m0s
[04:38:04.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:161:0)
[04:38:04.760] Timer tick, asking Buildbucket for the build status
[04:38:05.352] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:05.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:162:0) after 1m0s
[04:39:05.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:162:0)
[04:39:05.515] Timer tick, asking Buildbucket for the build status
[04:39:05.677] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:05.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:163:0) after 1m0s
[04:40:05.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:163:0)
[04:40:05.917] Timer tick, asking Buildbucket for the build status
[04:40:06.049] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:06.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:164:0) after 1m0s
[04:41:06.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:164:0)
[04:41:06.156] Timer tick, asking Buildbucket for the build status
[04:41:06.301] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:06.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:165:0) after 1m0s
[04:42:06.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:165:0)
[04:42:06.577] Timer tick, asking Buildbucket for the build status
[04:42:06.701] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:06.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:166:0) after 1m0s
[04:43:06.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:166:0)
[04:43:06.953] Timer tick, asking Buildbucket for the build status
[04:43:07.306] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:07.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:167:0) after 1m0s
[04:44:08.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:167:0)
[04:44:08.815] Timer tick, asking Buildbucket for the build status
[04:44:09.304] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:09.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:168:0) after 1m0s
[04:45:09.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:168:0)
[04:45:09.596] Timer tick, asking Buildbucket for the build status
[04:45:10.171] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:10.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:169:0) after 1m0s
[04:46:10.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:169:0)
[04:46:10.399] Timer tick, asking Buildbucket for the build status
[04:46:10.669] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:10.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:170:0) after 1m0s
[04:47:10.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:170:0)
[04:47:10.899] Timer tick, asking Buildbucket for the build status
[04:47:11.008] Build 8884622558162269248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:11.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050052620313605104:171:0) after 1m0s
[04:47:13.928] Received PubSub notification, asking Buildbucket for the build status
[04:47:14.075] Build 8884622558162269248: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:47:14.075] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1585370833200543",
"created_by": "project:infra",
"created_ts": "1585360677525204",
"failure_reason": "BUILD_FAILURE",
"id": "8884622558162269248",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.81\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"59ea4f52-2003-4e73-b3bd-28ea6822268b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.81\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Build chrome.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.81\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"59ea4f52-2003-4e73-b3bd-28ea6822268b\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-911e\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"81.0.4044.81\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-911e\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"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\": [\"4901-91f024a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Build chrome.') (retcode: 1)\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585368181178098",
"status": "COMPLETED",
"status_changed_ts": "1585368181845172",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9050052620313605104",
"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/8884622558162269248/+/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:4b37adb601be9c10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1585370833501027",
"url": "https://ci.chromium.org/b/8884622558162269248",
"utcnow_ts": "1585370834060580"
}
}
[04:47:14.075] Invocation finished in 2h49m17.985962961s with status FAILED