[07:54:48.174] New invocation is queued and will start shortly
[07:54:48.174] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:54:49.334] Starting the invocation (attempt 1)
[07:54:49.368] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:54:49.368] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:54:49.368] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9053291658925340896",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"82.0.4065.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"ddfd062c-4e09-4b82-96d8-4f11081ac744\",\"title\":\"publish_tarball/0\"}]},\"version\":\"82.0.4065.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9053291658925340896",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[07:54:49.911] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1582271689588351",
"id": "8887861596776731840",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4065.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ddfd062c-4e09-4b82-96d8-4f11081ac744\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4065.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582271689770330",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9053291658925340896",
"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/8887861596776731840/+/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": "1582271689770561",
"url": "https://ci.chromium.org/b/8887861596776731840",
"utcnow_ts": "1582271689904226"
}
}
[07:54:49.911] Task URL: https://ci.chromium.org/b/8887861596776731840
[07:54:49.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:2:0) after 1m0s
[07:55:49.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:2:0)
[07:55:49.972] Timer tick, asking Buildbucket for the build status
[07:55:50.973] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:50.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:3:0) after 1m0s
[07:56:51.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:3:0)
[07:56:51.444] Timer tick, asking Buildbucket for the build status
[07:56:51.532] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:51.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:4:0) after 1m0s
[07:57:51.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:4:0)
[07:57:51.652] Timer tick, asking Buildbucket for the build status
[07:57:51.770] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:51.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:5:0) after 1m0s
[07:58:51.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:5:0)
[07:58:51.832] Timer tick, asking Buildbucket for the build status
[07:58:51.965] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:51.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:6:0) after 1m0s
[07:59:52.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:6:0)
[07:59:52.018] Timer tick, asking Buildbucket for the build status
[07:59:52.191] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:52.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:7:0) after 1m0s
[08:00:52.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:7:0)
[08:00:52.512] Timer tick, asking Buildbucket for the build status
[08:00:52.661] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:52.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:8:0) after 1m0s
[08:01:52.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:8:0)
[08:01:52.861] Timer tick, asking Buildbucket for the build status
[08:01:53.017] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:53.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:9:0) after 1m0s
[08:02:53.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:9:0)
[08:02:53.118] Timer tick, asking Buildbucket for the build status
[08:02:53.282] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:53.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:10:0) after 1m0s
[08:03:53.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:10:0)
[08:03:53.398] Timer tick, asking Buildbucket for the build status
[08:03:54.147] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:54.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:11:0) after 1m0s
[08:04:54.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:11:0)
[08:04:54.238] Timer tick, asking Buildbucket for the build status
[08:04:54.444] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:54.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:12:0) after 1m0s
[08:05:54.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:12:0)
[08:05:54.503] Timer tick, asking Buildbucket for the build status
[08:05:54.737] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:54.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:13:0) after 1m0s
[08:06:54.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:13:0)
[08:06:54.849] Timer tick, asking Buildbucket for the build status
[08:06:55.003] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:55.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:14:0) after 1m0s
[08:07:55.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:14:0)
[08:07:55.136] Timer tick, asking Buildbucket for the build status
[08:07:55.567] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:55.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:15:0) after 1m0s
[08:08:55.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:15:0)
[08:08:55.632] Timer tick, asking Buildbucket for the build status
[08:08:56.065] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:56.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:16:0) after 1m0s
[08:09:56.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:16:0)
[08:09:56.127] Timer tick, asking Buildbucket for the build status
[08:09:56.321] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:56.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:17:0) after 1m0s
[08:10:56.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:17:0)
[08:10:56.359] Timer tick, asking Buildbucket for the build status
[08:10:56.532] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:56.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:18:0) after 1m0s
[08:11:56.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:18:0)
[08:11:56.593] Timer tick, asking Buildbucket for the build status
[08:11:56.781] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:56.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:19:0) after 1m0s
[08:12:56.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:19:0)
[08:12:56.802] Timer tick, asking Buildbucket for the build status
[08:12:56.920] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:56.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:20:0) after 1m0s
[08:13:56.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:20:0)
[08:13:56.979] Timer tick, asking Buildbucket for the build status
[08:13:57.093] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:57.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:21:0) after 1m0s
[08:14:57.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:21:0)
[08:14:57.119] Timer tick, asking Buildbucket for the build status
[08:14:57.226] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:57.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:22:0) after 1m0s
[08:15:57.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:22:0)
[08:15:57.295] Timer tick, asking Buildbucket for the build status
[08:15:57.516] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:57.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:23:0) after 1m0s
[08:16:57.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:23:0)
[08:16:57.720] Timer tick, asking Buildbucket for the build status
[08:16:57.947] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:57.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:24:0) after 1m0s
[08:17:58.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:24:0)
[08:17:58.080] Timer tick, asking Buildbucket for the build status
[08:17:58.206] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:58.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:25:0) after 1m0s
[08:18:58.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:25:0)
[08:18:58.348] Timer tick, asking Buildbucket for the build status
[08:18:58.447] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:58.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:26:0) after 1m0s
[08:19:58.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:26:0)
[08:19:58.574] Timer tick, asking Buildbucket for the build status
[08:19:58.660] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:58.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:27:0) after 1m0s
[08:20:58.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:27:0)
[08:20:58.796] Timer tick, asking Buildbucket for the build status
[08:20:58.936] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:58.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:28:0) after 1m0s
[08:21:59.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:28:0)
[08:21:59.085] Timer tick, asking Buildbucket for the build status
[08:21:59.213] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:59.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:29:0) after 1m0s
[08:22:59.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:29:0)
[08:22:59.284] Timer tick, asking Buildbucket for the build status
[08:22:59.564] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:59.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:30:0) after 1m0s
[08:23:59.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:30:0)
[08:23:59.666] Timer tick, asking Buildbucket for the build status
[08:23:59.908] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:59.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:31:0) after 1m0s
[08:24:59.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:31:0)
[08:24:59.926] Timer tick, asking Buildbucket for the build status
[08:25:00.142] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:00.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:32:0) after 1m0s
[08:26:00.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:32:0)
[08:26:00.249] Timer tick, asking Buildbucket for the build status
[08:26:00.375] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:00.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:33:0) after 1m0s
[08:27:00.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:33:0)
[08:27:00.420] Timer tick, asking Buildbucket for the build status
[08:27:00.538] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:00.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:34:0) after 1m0s
[08:28:00.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:34:0)
[08:28:00.618] Timer tick, asking Buildbucket for the build status
[08:28:00.720] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:00.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:35:0) after 1m0s
[08:29:00.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:35:0)
[08:29:00.848] Timer tick, asking Buildbucket for the build status
[08:29:01.023] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:01.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:36:0) after 1m0s
[08:30:01.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:36:0)
[08:30:01.215] Timer tick, asking Buildbucket for the build status
[08:30:01.528] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:01.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:37:0) after 1m0s
[08:31:01.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:37:0)
[08:31:01.854] Timer tick, asking Buildbucket for the build status
[08:31:02.021] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:02.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:38:0) after 1m0s
[08:32:02.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:38:0)
[08:32:02.152] Timer tick, asking Buildbucket for the build status
[08:32:02.698] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:02.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:39:0) after 1m0s
[08:33:02.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:39:0)
[08:33:02.903] Timer tick, asking Buildbucket for the build status
[08:33:03.223] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:03.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:40:0) after 1m0s
[08:34:03.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:40:0)
[08:34:03.330] Timer tick, asking Buildbucket for the build status
[08:34:03.699] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:03.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:41:0) after 1m0s
[08:35:03.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:41:0)
[08:35:03.793] Timer tick, asking Buildbucket for the build status
[08:35:03.896] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:03.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:42:0) after 1m0s
[08:36:03.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:42:0)
[08:36:03.984] Timer tick, asking Buildbucket for the build status
[08:36:04.349] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:04.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:43:0) after 1m0s
[08:37:04.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:43:0)
[08:37:04.390] Timer tick, asking Buildbucket for the build status
[08:37:04.497] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:04.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:44:0) after 1m0s
[08:38:04.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:44:0)
[08:38:04.723] Timer tick, asking Buildbucket for the build status
[08:38:05.217] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:05.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:45:0) after 1m0s
[08:39:05.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:45:0)
[08:39:05.317] Timer tick, asking Buildbucket for the build status
[08:39:05.505] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:05.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:46:0) after 1m0s
[08:40:05.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:46:0)
[08:40:05.579] Timer tick, asking Buildbucket for the build status
[08:40:05.690] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:05.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:47:0) after 1m0s
[08:41:05.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:47:0)
[08:41:05.832] Timer tick, asking Buildbucket for the build status
[08:41:06.001] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:06.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:48:0) after 1m0s
[08:42:06.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:48:0)
[08:42:06.157] Timer tick, asking Buildbucket for the build status
[08:42:06.385] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:06.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:49:0) after 1m0s
[08:43:06.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:49:0)
[08:43:06.685] Timer tick, asking Buildbucket for the build status
[08:43:06.941] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:06.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:50:0) after 1m0s
[08:44:07.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:50:0)
[08:44:07.072] Timer tick, asking Buildbucket for the build status
[08:44:07.504] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:07.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:51:0) after 1m0s
[08:45:07.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:51:0)
[08:45:07.740] Timer tick, asking Buildbucket for the build status
[08:45:07.917] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:07.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:52:0) after 1m0s
[08:46:07.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:52:0)
[08:46:07.974] Timer tick, asking Buildbucket for the build status
[08:46:08.123] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:08.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:53:0) after 1m0s
[08:47:08.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:53:0)
[08:47:08.273] Timer tick, asking Buildbucket for the build status
[08:47:08.655] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:08.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:54:0) after 1m0s
[08:48:08.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:54:0)
[08:48:08.641] Timer tick, asking Buildbucket for the build status
[08:48:08.811] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:08.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:55:0) after 1m0s
[08:49:08.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:55:0)
[08:49:08.922] Timer tick, asking Buildbucket for the build status
[08:49:09.063] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:09.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:56:0) after 1m0s
[08:50:09.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:56:0)
[08:50:09.064] Timer tick, asking Buildbucket for the build status
[08:50:09.445] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:09.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:57:0) after 1m0s
[08:51:09.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:57:0)
[08:51:09.570] Timer tick, asking Buildbucket for the build status
[08:51:09.772] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:09.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:58:0) after 1m0s
[08:52:09.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:58:0)
[08:52:09.866] Timer tick, asking Buildbucket for the build status
[08:52:10.156] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:10.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:59:0) after 1m0s
[08:53:10.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:59:0)
[08:53:10.193] Timer tick, asking Buildbucket for the build status
[08:53:10.313] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:10.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:60:0) after 1m0s
[08:54:10.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:60:0)
[08:54:10.666] Timer tick, asking Buildbucket for the build status
[08:54:11.004] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:11.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:61:0) after 1m0s
[08:55:11.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:61:0)
[08:55:11.049] Timer tick, asking Buildbucket for the build status
[08:55:11.262] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:11.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:62:0) after 1m0s
[08:56:11.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:62:0)
[08:56:11.354] Timer tick, asking Buildbucket for the build status
[08:56:11.484] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:11.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:63:0) after 1m0s
[08:57:11.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:63:0)
[08:57:11.506] Timer tick, asking Buildbucket for the build status
[08:57:11.615] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:11.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:64:0) after 1m0s
[08:58:11.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:64:0)
[08:58:11.672] Timer tick, asking Buildbucket for the build status
[08:58:11.835] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:11.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:65:0) after 1m0s
[08:59:11.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:65:0)
[08:59:11.902] Timer tick, asking Buildbucket for the build status
[08:59:12.087] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:12.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:66:0) after 1m0s
[09:00:12.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:66:0)
[09:00:12.140] Timer tick, asking Buildbucket for the build status
[09:00:12.402] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:12.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:67:0) after 1m0s
[09:01:12.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:67:0)
[09:01:12.662] Timer tick, asking Buildbucket for the build status
[09:01:12.778] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:12.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:68:0) after 1m0s
[09:02:12.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:68:0)
[09:02:12.887] Timer tick, asking Buildbucket for the build status
[09:02:13.055] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:13.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:69:0) after 1m0s
[09:03:13.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:69:0)
[09:03:13.191] Timer tick, asking Buildbucket for the build status
[09:03:13.369] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:13.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:70:0) after 1m0s
[09:04:13.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:70:0)
[09:04:13.611] Timer tick, asking Buildbucket for the build status
[09:04:13.739] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:13.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:71:0) after 1m0s
[09:05:13.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:71:0)
[09:05:13.899] Timer tick, asking Buildbucket for the build status
[09:05:14.021] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:14.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:72:0) after 1m0s
[09:06:14.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:72:0)
[09:06:14.109] Timer tick, asking Buildbucket for the build status
[09:06:14.203] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:14.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:73:0) after 1m0s
[09:07:14.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:73:0)
[09:07:14.412] Timer tick, asking Buildbucket for the build status
[09:07:14.615] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:14.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:74:0) after 1m0s
[09:08:14.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:74:0)
[09:08:14.656] Timer tick, asking Buildbucket for the build status
[09:08:14.759] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:14.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:75:0) after 1m0s
[09:09:14.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:75:0)
[09:09:14.782] Timer tick, asking Buildbucket for the build status
[09:09:14.880] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:14.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:76:0) after 1m0s
[09:10:14.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:76:0)
[09:10:14.963] Timer tick, asking Buildbucket for the build status
[09:10:15.314] Build 8887861596776731840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:15.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:77:0) after 1m0s
[09:10:46.015] Received PubSub notification, asking Buildbucket for the build status
[09:10:46.799] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:15.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:77:0)
[09:11:15.401] Timer tick, asking Buildbucket for the build status
[09:11:15.704] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:15.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:79:0) after 1m0s
[09:12:15.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:79:0)
[09:12:15.784] Timer tick, asking Buildbucket for the build status
[09:12:15.987] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:15.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:80:0) after 1m0s
[09:13:16.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:80:0)
[09:13:16.016] Timer tick, asking Buildbucket for the build status
[09:13:16.255] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:16.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:81:0) after 1m0s
[09:14:16.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:81:0)
[09:14:16.417] Timer tick, asking Buildbucket for the build status
[09:14:16.542] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:16.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:82:0) after 1m0s
[09:15:16.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:82:0)
[09:15:16.779] Timer tick, asking Buildbucket for the build status
[09:15:16.951] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:16.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:83:0) after 1m0s
[09:16:17.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:83:0)
[09:16:17.016] Timer tick, asking Buildbucket for the build status
[09:16:17.413] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:17.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:84:0) after 1m0s
[09:17:17.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:84:0)
[09:17:17.435] Timer tick, asking Buildbucket for the build status
[09:17:17.864] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:17.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:85:0) after 1m0s
[09:18:17.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:85:0)
[09:18:17.949] Timer tick, asking Buildbucket for the build status
[09:18:18.130] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:18.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:86:0) after 1m0s
[09:19:18.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:86:0)
[09:19:18.349] Timer tick, asking Buildbucket for the build status
[09:19:18.523] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:18.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:87:0) after 1m0s
[09:20:18.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:87:0)
[09:20:18.597] Timer tick, asking Buildbucket for the build status
[09:20:18.777] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:18.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:88:0) after 1m0s
[09:21:18.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:88:0)
[09:21:18.835] Timer tick, asking Buildbucket for the build status
[09:21:18.995] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:18.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:89:0) after 1m0s
[09:22:19.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:89:0)
[09:22:19.018] Timer tick, asking Buildbucket for the build status
[09:22:19.622] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:19.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:90:0) after 1m0s
[09:23:19.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:90:0)
[09:23:19.674] Timer tick, asking Buildbucket for the build status
[09:23:20.191] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:20.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:91:0) after 1m0s
[09:24:20.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:91:0)
[09:24:20.214] Timer tick, asking Buildbucket for the build status
[09:24:20.338] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:20.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:92:0) after 1m0s
[09:25:20.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:92:0)
[09:25:20.368] Timer tick, asking Buildbucket for the build status
[09:25:21.162] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:21.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:93:0) after 1m0s
[09:26:21.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:93:0)
[09:26:21.265] Timer tick, asking Buildbucket for the build status
[09:26:21.406] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:21.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:94:0) after 1m0s
[09:27:21.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:94:0)
[09:27:21.414] Timer tick, asking Buildbucket for the build status
[09:27:21.526] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:21.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:95:0) after 1m0s
[09:28:21.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:95:0)
[09:28:21.590] Timer tick, asking Buildbucket for the build status
[09:28:21.865] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:21.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:96:0) after 1m0s
[09:29:21.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:96:0)
[09:29:21.887] Timer tick, asking Buildbucket for the build status
[09:29:22.117] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:22.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:97:0) after 1m0s
[09:30:22.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:97:0)
[09:30:22.185] Timer tick, asking Buildbucket for the build status
[09:30:22.632] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:22.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:98:0) after 1m0s
[09:31:22.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:98:0)
[09:31:22.671] Timer tick, asking Buildbucket for the build status
[09:31:22.804] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:22.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:99:0) after 1m0s
[09:32:22.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:99:0)
[09:32:22.830] Timer tick, asking Buildbucket for the build status
[09:32:23.141] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:23.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:100:0) after 1m0s
[09:33:23.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:100:0)
[09:33:23.195] Timer tick, asking Buildbucket for the build status
[09:33:23.304] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:23.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:101:0) after 1m0s
[09:34:23.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:101:0)
[09:34:23.358] Timer tick, asking Buildbucket for the build status
[09:34:23.480] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:23.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:102:0) after 1m0s
[09:35:23.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:102:0)
[09:35:23.532] Timer tick, asking Buildbucket for the build status
[09:35:24.174] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:24.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:103:0) after 1m0s
[09:36:24.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:103:0)
[09:36:24.428] Timer tick, asking Buildbucket for the build status
[09:36:24.672] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:24.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:104:0) after 1m0s
[09:37:24.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:104:0)
[09:37:24.703] Timer tick, asking Buildbucket for the build status
[09:37:24.839] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:24.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:105:0) after 1m0s
[09:38:24.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:105:0)
[09:38:24.981] Timer tick, asking Buildbucket for the build status
[09:38:25.138] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:25.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:106:0) after 1m0s
[09:39:25.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:106:0)
[09:39:25.238] Timer tick, asking Buildbucket for the build status
[09:39:25.763] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:25.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:107:0) after 1m0s
[09:40:25.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:107:0)
[09:40:25.829] Timer tick, asking Buildbucket for the build status
[09:40:26.458] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:26.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:108:0) after 1m0s
[09:41:26.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:108:0)
[09:41:26.556] Timer tick, asking Buildbucket for the build status
[09:41:26.722] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:26.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:109:0) after 1m0s
[09:42:26.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:109:0)
[09:42:26.882] Timer tick, asking Buildbucket for the build status
[09:42:27.024] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:27.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:110:0) after 1m0s
[09:43:27.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:110:0)
[09:43:27.097] Timer tick, asking Buildbucket for the build status
[09:43:27.226] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:27.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:111:0) after 1m0s
[09:44:27.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:111:0)
[09:44:27.258] Timer tick, asking Buildbucket for the build status
[09:44:27.649] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:27.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:112:0) after 1m0s
[09:45:27.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:112:0)
[09:45:27.680] Timer tick, asking Buildbucket for the build status
[09:45:28.725] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:28.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:113:0) after 1m0s
[09:46:28.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:113:0)
[09:46:28.718] Timer tick, asking Buildbucket for the build status
[09:46:28.894] Build 8887861596776731840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:28.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9053291658925340896:114:0) after 1m0s
[09:46:52.326] Received PubSub notification, asking Buildbucket for the build status
[09:46:52.406] Build 8887861596776731840: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:46:52.406] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1582278411066860",
"created_by": "project:infra",
"created_ts": "1582271689588351",
"failure_reason": "BUILD_FAILURE",
"id": "8887861596776731840",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4065.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ddfd062c-4e09-4b82-96d8-4f11081ac744\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4065.0\"}}",
"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\": \"82.0.4065.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ddfd062c-4e09-4b82-96d8-4f11081ac744\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-4lhg\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"82.0.4065.0\"}, \"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-4lhg\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4825-3d129d6\"], \"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": "1582276245150649",
"status": "COMPLETED",
"status_changed_ts": "1582276245446196",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9053291658925340896",
"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/8887861596776731840/+/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:4a7f8f7e771b8510",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1582278411553811",
"url": "https://ci.chromium.org/b/8887861596776731840",
"utcnow_ts": "1582278412393500"
}
}
[09:46:52.406] Invocation finished in 1h52m4.251459506s with status FAILED