[12:37:02.360] New invocation is queued and will start shortly
[12:37:03.445] Starting the invocation (attempt 1)
[12:37:03.531] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:37:03.531] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:37:03.532] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9030715257509322864",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"14082\"},\"id\":\"cron:v1:14082\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9030715257509322864",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[12:37:03.883] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1603802223622205",
"id": "8865285195522099504",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"14082\"}, \"id\": \"cron:v1:14082\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603802223781076",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9030715257509322864",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8865285195522099504/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:publish_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1603802223781188",
"url": "https://ci.chromium.org/b/8865285195522099504",
"utcnow_ts": "1603802223873435"
}
}
[12:37:03.883] Task URL: https://ci.chromium.org/b/8865285195522099504
[12:37:03.883] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:2:0) after 1m0s
[12:38:03.997] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:2:0)
[12:38:03.997] Timer tick, asking Buildbucket for the build status
[12:38:04.230] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:04.230] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:3:0) after 1m0s
[12:39:04.572] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:3:0)
[12:39:04.572] Timer tick, asking Buildbucket for the build status
[12:39:04.671] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:04.671] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:4:0) after 1m0s
[12:40:05.012] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:4:0)
[12:40:05.012] Timer tick, asking Buildbucket for the build status
[12:40:05.097] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:05.098] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:5:0) after 1m0s
[12:41:05.272] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:5:0)
[12:41:05.272] Timer tick, asking Buildbucket for the build status
[12:41:05.704] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:05.704] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:6:0) after 1m0s
[12:42:05.883] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:6:0)
[12:42:05.883] Timer tick, asking Buildbucket for the build status
[12:42:05.994] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:05.994] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:7:0) after 1m0s
[12:43:06.113] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:7:0)
[12:43:06.113] Timer tick, asking Buildbucket for the build status
[12:43:06.682] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:06.683] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:8:0) after 1m0s
[12:44:06.873] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:8:0)
[12:44:06.873] Timer tick, asking Buildbucket for the build status
[12:44:07.157] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:07.157] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:9:0) after 1m0s
[12:45:07.261] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:9:0)
[12:45:07.261] Timer tick, asking Buildbucket for the build status
[12:45:07.338] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:07.338] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:10:0) after 1m0s
[12:46:07.549] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:10:0)
[12:46:07.549] Timer tick, asking Buildbucket for the build status
[12:46:07.637] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:07.637] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:11:0) after 1m0s
[12:47:07.720] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:11:0)
[12:47:07.720] Timer tick, asking Buildbucket for the build status
[12:47:07.934] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:07.934] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:12:0) after 1m0s
[12:48:08.083] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:12:0)
[12:48:08.083] Timer tick, asking Buildbucket for the build status
[12:48:08.168] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:08.168] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:13:0) after 1m0s
[12:49:08.267] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:13:0)
[12:49:08.267] Timer tick, asking Buildbucket for the build status
[12:49:08.350] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:08.350] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:14:0) after 1m0s
[12:50:08.446] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:14:0)
[12:50:08.446] Timer tick, asking Buildbucket for the build status
[12:50:08.572] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:08.572] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:15:0) after 1m0s
[12:51:08.565] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:15:0)
[12:51:08.565] Timer tick, asking Buildbucket for the build status
[12:51:08.638] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:08.638] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:16:0) after 1m0s
[12:52:08.754] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:16:0)
[12:52:08.754] Timer tick, asking Buildbucket for the build status
[12:52:08.885] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:08.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:17:0) after 1m0s
[12:53:09.185] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:17:0)
[12:53:09.185] Timer tick, asking Buildbucket for the build status
[12:53:09.300] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:09.300] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:18:0) after 1m0s
[12:54:09.527] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:18:0)
[12:54:09.527] Timer tick, asking Buildbucket for the build status
[12:54:09.662] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:09.662] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:19:0) after 1m0s
[12:55:09.838] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:19:0)
[12:55:09.838] Timer tick, asking Buildbucket for the build status
[12:55:09.966] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:09.966] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:20:0) after 1m0s
[12:56:10.074] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:20:0)
[12:56:10.074] Timer tick, asking Buildbucket for the build status
[12:56:10.233] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:10.233] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:21:0) after 1m0s
[12:57:10.378] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:21:0)
[12:57:10.378] Timer tick, asking Buildbucket for the build status
[12:57:10.495] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:10.495] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:22:0) after 1m0s
[12:58:10.582] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:22:0)
[12:58:10.582] Timer tick, asking Buildbucket for the build status
[12:58:10.675] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:10.675] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:23:0) after 1m0s
[12:59:10.786] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:23:0)
[12:59:10.786] Timer tick, asking Buildbucket for the build status
[12:59:10.873] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:10.873] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:24:0) after 1m0s
[13:00:11.289] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:24:0)
[13:00:11.289] Timer tick, asking Buildbucket for the build status
[13:00:12.062] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:12.062] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:25:0) after 1m0s
[13:01:12.284] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:25:0)
[13:01:12.284] Timer tick, asking Buildbucket for the build status
[13:01:12.391] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:12.391] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:26:0) after 1m0s
[13:02:12.490] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:26:0)
[13:02:12.490] Timer tick, asking Buildbucket for the build status
[13:02:13.588] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:13.588] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:27:0) after 1m0s
[13:03:13.700] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:27:0)
[13:03:13.700] Timer tick, asking Buildbucket for the build status
[13:03:13.923] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:13.923] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:28:0) after 1m0s
[13:04:13.929] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:28:0)
[13:04:13.929] Timer tick, asking Buildbucket for the build status
[13:04:14.094] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:14.094] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:29:0) after 1m0s
[13:05:14.116] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:29:0)
[13:05:14.116] Timer tick, asking Buildbucket for the build status
[13:05:14.315] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:14.315] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:30:0) after 1m0s
[13:06:14.373] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:30:0)
[13:06:14.373] Timer tick, asking Buildbucket for the build status
[13:06:14.486] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:14.486] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:31:0) after 1m0s
[13:07:14.572] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:31:0)
[13:07:14.572] Timer tick, asking Buildbucket for the build status
[13:07:14.743] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:14.743] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:32:0) after 1m0s
[13:08:14.929] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:32:0)
[13:08:14.929] Timer tick, asking Buildbucket for the build status
[13:08:15.082] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:15.082] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:33:0) after 1m0s
[13:09:15.130] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:33:0)
[13:09:15.130] Timer tick, asking Buildbucket for the build status
[13:09:15.252] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:15.252] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:34:0) after 1m0s
[13:10:15.282] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:34:0)
[13:10:15.282] Timer tick, asking Buildbucket for the build status
[13:10:15.442] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:15.442] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:35:0) after 1m0s
[13:11:15.510] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:35:0)
[13:11:15.510] Timer tick, asking Buildbucket for the build status
[13:11:15.619] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:15.619] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:36:0) after 1m0s
[13:12:15.695] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:36:0)
[13:12:15.695] Timer tick, asking Buildbucket for the build status
[13:12:15.956] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:15.956] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:37:0) after 1m0s
[13:13:16.200] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:37:0)
[13:13:16.200] Timer tick, asking Buildbucket for the build status
[13:13:16.699] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:16.699] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:38:0) after 1m0s
[13:14:16.825] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:38:0)
[13:14:16.825] Timer tick, asking Buildbucket for the build status
[13:14:16.980] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:16.980] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:39:0) after 1m0s
[13:15:17.098] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:39:0)
[13:15:17.098] Timer tick, asking Buildbucket for the build status
[13:15:17.219] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:17.219] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:40:0) after 1m0s
[13:16:17.338] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:40:0)
[13:16:17.338] Timer tick, asking Buildbucket for the build status
[13:16:18.718] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:18.719] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:41:0) after 1m0s
[13:17:18.775] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:41:0)
[13:17:18.775] Timer tick, asking Buildbucket for the build status
[13:17:18.955] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:18.955] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:42:0) after 1m0s
[13:18:19.110] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:42:0)
[13:18:19.111] Timer tick, asking Buildbucket for the build status
[13:18:19.220] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:19.220] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:43:0) after 1m0s
[13:19:19.342] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:43:0)
[13:19:19.342] Timer tick, asking Buildbucket for the build status
[13:19:19.437] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:19.437] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:44:0) after 1m0s
[13:20:19.821] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:44:0)
[13:20:19.821] Timer tick, asking Buildbucket for the build status
[13:20:20.013] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:20.013] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:45:0) after 1m0s
[13:21:20.059] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:45:0)
[13:21:20.059] Timer tick, asking Buildbucket for the build status
[13:21:20.170] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:20.170] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:46:0) after 1m0s
[13:22:20.241] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:46:0)
[13:22:20.241] Timer tick, asking Buildbucket for the build status
[13:22:20.395] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:20.395] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:47:0) after 1m0s
[13:23:20.498] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:47:0)
[13:23:20.498] Timer tick, asking Buildbucket for the build status
[13:23:20.587] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:20.587] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:48:0) after 1m0s
[13:24:20.632] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:48:0)
[13:24:20.632] Timer tick, asking Buildbucket for the build status
[13:24:20.733] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:20.733] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:49:0) after 1m0s
[13:25:20.782] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:49:0)
[13:25:20.782] Timer tick, asking Buildbucket for the build status
[13:25:20.881] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:20.881] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:50:0) after 1m0s
[13:26:20.976] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:50:0)
[13:26:20.976] Timer tick, asking Buildbucket for the build status
[13:26:21.065] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:21.065] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:51:0) after 1m0s
[13:27:21.171] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:51:0)
[13:27:21.171] Timer tick, asking Buildbucket for the build status
[13:27:21.311] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:21.311] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:52:0) after 1m0s
[13:28:21.369] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:52:0)
[13:28:21.369] Timer tick, asking Buildbucket for the build status
[13:28:21.566] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:21.566] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:53:0) after 1m0s
[13:29:21.610] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:53:0)
[13:29:21.610] Timer tick, asking Buildbucket for the build status
[13:29:22.428] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:22.428] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:54:0) after 1m0s
[13:30:22.534] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:54:0)
[13:30:22.534] Timer tick, asking Buildbucket for the build status
[13:30:22.623] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:22.623] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:55:0) after 1m0s
[13:31:22.728] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:55:0)
[13:31:22.728] Timer tick, asking Buildbucket for the build status
[13:31:22.823] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:22.823] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:56:0) after 1m0s
[13:32:22.886] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:56:0)
[13:32:22.886] Timer tick, asking Buildbucket for the build status
[13:32:22.975] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:22.975] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:57:0) after 1m0s
[13:33:23.067] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:57:0)
[13:33:23.068] Timer tick, asking Buildbucket for the build status
[13:33:23.236] Build 8865285195522099504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:23.236] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9030715257509322864:58:0) after 1m0s
[13:33:30.538] Received PubSub notification, asking Buildbucket for the build status
[13:33:30.842] Build 8865285195522099504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:19.774] Received PubSub notification, asking Buildbucket for the build status
[13:34:20.530] Build 8865285195522099504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:34:20.530] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1603805656923620",
"created_by": "project:infra",
"created_ts": "1603802223622205",
"id": "8865285195522099504",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"14082\"}, \"id\": \"cron:v1:14082\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-1-h9w2\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5613-2fddb4d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603805609414228",
"status": "COMPLETED",
"status_changed_ts": "1603805657695483",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9030715257509322864",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8865285195522099504/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:publish_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:4f82e15d54133710",
"user_agent:luci-scheduler"
],
"updated_ts": "1603805657756645",
"url": "https://ci.chromium.org/b/8865285195522099504",
"utcnow_ts": "1603805660473167"
}
}
[13:34:20.530] Invocation finished in 57m18.187715929s with status SUCCEEDED