[03:02:00.737] New invocation is queued and will start shortly
[03:02:00.737] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[03:02:02.232] Starting the invocation (attempt 1)
[03:02:02.267] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:02.267] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:02.267] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9054034855445351312",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"81.0.4044.15\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"4527a129-1d77-46f6-ae60-bcec78089ac8\",\"title\":\"publish_tarball/0\"}]},\"version\":\"81.0.4044.15\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9054034855445351312",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[03:02:03.058] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1581562922475317",
"id": "8888604792960956560",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.15\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"4527a129-1d77-46f6-ae60-bcec78089ac8\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.15\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581562922782114",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9054034855445351312",
"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/8888604792960956560/+/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": "1581562922782236",
"url": "https://ci.chromium.org/b/8888604792960956560",
"utcnow_ts": "1581562923050474"
}
}
[03:02:03.058] Task URL: https://ci.chromium.org/b/8888604792960956560
[03:02:03.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:2:0) after 1m0s
[03:02:48.752] Received PubSub notification, asking Buildbucket for the build status
[03:02:48.807] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:03.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:2:0)
[03:03:03.046] Timer tick, asking Buildbucket for the build status
[03:03:03.777] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:03.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:4:0) after 1m0s
[03:04:03.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:4:0)
[03:04:03.793] Timer tick, asking Buildbucket for the build status
[03:04:04.225] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:04.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:5:0) after 1m0s
[03:05:04.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:5:0)
[03:05:04.252] Timer tick, asking Buildbucket for the build status
[03:05:04.952] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:04.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:6:0) after 1m0s
[03:06:05.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:6:0)
[03:06:05.047] Timer tick, asking Buildbucket for the build status
[03:06:05.327] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:05.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:7:0) after 1m0s
[03:07:05.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:7:0)
[03:07:05.382] Timer tick, asking Buildbucket for the build status
[03:07:05.755] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:05.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:8:0) after 1m0s
[03:08:05.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:8:0)
[03:08:05.853] Timer tick, asking Buildbucket for the build status
[03:08:06.655] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:06.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:9:0) after 1m0s
[03:09:06.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:9:0)
[03:09:06.838] Timer tick, asking Buildbucket for the build status
[03:09:07.488] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:10:0) after 1m0s
[03:10:07.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:10:0)
[03:10:07.648] Timer tick, asking Buildbucket for the build status
[03:10:07.777] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:07.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:11:0) after 1m0s
[03:11:07.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:11:0)
[03:11:07.890] Timer tick, asking Buildbucket for the build status
[03:11:08.176] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:08.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:12:0) after 1m0s
[03:12:08.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:12:0)
[03:12:08.355] Timer tick, asking Buildbucket for the build status
[03:12:08.862] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:08.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:13:0) after 1m0s
[03:13:08.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:13:0)
[03:13:08.929] Timer tick, asking Buildbucket for the build status
[03:13:09.119] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:09.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:14:0) after 1m0s
[03:14:09.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:14:0)
[03:14:09.193] Timer tick, asking Buildbucket for the build status
[03:14:09.300] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:09.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:15:0) after 1m0s
[03:15:09.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:15:0)
[03:15:09.302] Timer tick, asking Buildbucket for the build status
[03:15:09.725] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:09.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:16:0) after 1m0s
[03:16:09.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:16:0)
[03:16:09.756] Timer tick, asking Buildbucket for the build status
[03:16:10.389] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:17:0) after 1m0s
[03:17:10.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:17:0)
[03:17:10.390] Timer tick, asking Buildbucket for the build status
[03:17:10.757] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:10.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:18:0) after 1m0s
[03:18:10.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:18:0)
[03:18:10.757] Timer tick, asking Buildbucket for the build status
[03:18:11.505] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:11.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:19:0) after 1m0s
[03:19:11.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:19:0)
[03:19:11.543] Timer tick, asking Buildbucket for the build status
[03:19:11.916] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:11.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:20:0) after 1m0s
[03:20:11.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:20:0)
[03:20:11.993] Timer tick, asking Buildbucket for the build status
[03:20:12.341] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:21:0) after 1m0s
[03:21:12.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:21:0)
[03:21:12.497] Timer tick, asking Buildbucket for the build status
[03:21:12.759] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:12.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:22:0) after 1m0s
[03:22:12.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:22:0)
[03:22:12.924] Timer tick, asking Buildbucket for the build status
[03:22:13.053] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:13.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:23:0) after 1m0s
[03:23:13.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:23:0)
[03:23:13.213] Timer tick, asking Buildbucket for the build status
[03:23:13.382] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:13.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:24:0) after 1m0s
[03:24:13.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:24:0)
[03:24:13.468] Timer tick, asking Buildbucket for the build status
[03:24:13.612] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:13.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:25:0) after 1m0s
[03:25:13.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:25:0)
[03:25:13.691] Timer tick, asking Buildbucket for the build status
[03:25:14.221] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:14.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:26:0) after 1m0s
[03:26:14.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:26:0)
[03:26:14.861] Timer tick, asking Buildbucket for the build status
[03:26:15.221] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:15.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:27:0) after 1m0s
[03:27:15.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:27:0)
[03:27:15.511] Timer tick, asking Buildbucket for the build status
[03:27:15.606] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:15.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:28:0) after 1m0s
[03:28:15.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:28:0)
[03:28:15.698] Timer tick, asking Buildbucket for the build status
[03:28:15.796] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:15.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:29:0) after 1m0s
[03:29:16.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:29:0)
[03:29:16.009] Timer tick, asking Buildbucket for the build status
[03:29:16.117] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:16.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:30:0) after 1m0s
[03:30:16.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:30:0)
[03:30:16.211] Timer tick, asking Buildbucket for the build status
[03:30:16.312] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:16.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:31:0) after 1m0s
[03:31:16.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:31:0)
[03:31:16.387] Timer tick, asking Buildbucket for the build status
[03:31:18.410] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:18.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:32:0) after 1m0s
[03:32:18.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:32:0)
[03:32:18.462] Timer tick, asking Buildbucket for the build status
[03:32:19.920] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:19.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:33:0) after 1m0s
[03:33:20.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:33:0)
[03:33:20.082] Timer tick, asking Buildbucket for the build status
[03:33:20.335] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:20.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:34:0) after 1m0s
[03:34:20.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:34:0)
[03:34:20.534] Timer tick, asking Buildbucket for the build status
[03:34:20.724] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:20.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:35:0) after 1m0s
[03:35:21.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:35:0)
[03:35:21.060] Timer tick, asking Buildbucket for the build status
[03:35:21.234] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:21.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:36:0) after 1m0s
[03:36:21.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:36:0)
[03:36:21.285] Timer tick, asking Buildbucket for the build status
[03:36:21.388] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:21.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:37:0) after 1m0s
[03:37:21.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:37:0)
[03:37:21.858] Timer tick, asking Buildbucket for the build status
[03:37:22.094] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:38:0) after 1m0s
[03:38:22.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:38:0)
[03:38:22.432] Timer tick, asking Buildbucket for the build status
[03:38:22.979] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:22.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:39:0) after 1m0s
[03:39:23.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:39:0)
[03:39:23.199] Timer tick, asking Buildbucket for the build status
[03:39:24.252] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:24.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:40:0) after 1m0s
[03:40:24.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:40:0)
[03:40:24.453] Timer tick, asking Buildbucket for the build status
[03:40:24.618] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:24.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:41:0) after 1m0s
[03:41:24.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:41:0)
[03:41:24.673] Timer tick, asking Buildbucket for the build status
[03:41:24.824] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:24.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:42:0) after 1m0s
[03:42:25.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:42:0)
[03:42:25.155] Timer tick, asking Buildbucket for the build status
[03:42:25.850] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:25.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:43:0) after 1m0s
[03:43:26.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:43:0)
[03:43:26.094] Timer tick, asking Buildbucket for the build status
[03:43:26.209] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:26.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:44:0) after 1m0s
[03:44:26.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:44:0)
[03:44:26.419] Timer tick, asking Buildbucket for the build status
[03:44:26.542] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:26.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:45:0) after 1m0s
[03:45:26.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:45:0)
[03:45:26.671] Timer tick, asking Buildbucket for the build status
[03:45:26.831] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:26.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:46:0) after 1m0s
[03:46:27.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:46:0)
[03:46:27.004] Timer tick, asking Buildbucket for the build status
[03:46:27.370] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:47:0) after 1m0s
[03:47:27.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:47:0)
[03:47:27.472] Timer tick, asking Buildbucket for the build status
[03:47:27.614] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:27.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:48:0) after 1m0s
[03:48:27.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:48:0)
[03:48:27.634] Timer tick, asking Buildbucket for the build status
[03:48:27.759] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:27.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:49:0) after 1m0s
[03:49:27.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:49:0)
[03:49:27.796] Timer tick, asking Buildbucket for the build status
[03:49:27.889] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:27.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:50:0) after 1m0s
[03:50:28.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:50:0)
[03:50:28.085] Timer tick, asking Buildbucket for the build status
[03:50:28.226] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:28.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:51:0) after 1m0s
[03:51:28.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:51:0)
[03:51:28.289] Timer tick, asking Buildbucket for the build status
[03:51:28.607] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:28.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:52:0) after 1m0s
[03:52:28.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:52:0)
[03:52:28.749] Timer tick, asking Buildbucket for the build status
[03:52:29.090] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:29.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:53:0) after 1m0s
[03:53:29.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:53:0)
[03:53:29.113] Timer tick, asking Buildbucket for the build status
[03:53:29.258] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:29.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:54:0) after 1m0s
[03:54:29.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:54:0)
[03:54:29.466] Timer tick, asking Buildbucket for the build status
[03:54:29.691] Build 8888604792960956560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:29.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054034855445351312:55:0) after 1m0s
[03:55:14.302] Received PubSub notification, asking Buildbucket for the build status
[03:55:14.443] Build 8888604792960956560: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:55:14.443] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1581566113864541",
"created_by": "project:infra",
"created_ts": "1581562922475317",
"failure_reason": "BUILD_FAILURE",
"id": "8888604792960956560",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.15\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"4527a129-1d77-46f6-ae60-bcec78089ac8\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.15\"}}",
"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.15\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"4527a129-1d77-46f6-ae60-bcec78089ac8\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-vxq7\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"81.0.4044.15\"}, \"ui\": {\"info\": \"Step('Build chrome.') (retcode: 1)\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581562968222574",
"status": "COMPLETED",
"status_changed_ts": "1581562922782114",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9054034855445351312",
"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/8888604792960956560/+/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:4a555090c324c910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1581566114037460",
"url": "https://ci.chromium.org/b/8888604792960956560",
"utcnow_ts": "1581566114435033"
}
}
[03:55:14.443] Invocation finished in 53m13.72090805s with status FAILED