[18:37:02.898] New invocation is queued and will start shortly
[18:37:04.446] Starting the invocation (attempt 1)
[18:37:04.539] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:37:04.539] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:37:04.539] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9072004824380547984",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"3144\"},\"id\":\"cron:v1:3144\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9072004824380547984",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[18:37:05.324] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1564425424765104",
"id": "8906574761759848400",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"3144\"}, \"id\": \"cron:v1:3144\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564425424993483",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9072004824380547984",
"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/8906574761759848400/+/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": "1564425424993632",
"url": "https://ci.chromium.org/b/8906574761759848400",
"utcnow_ts": "1564425425286715"
}
}
[18:37:05.324] Task URL: https://ci.chromium.org/b/8906574761759848400
[18:37:05.324] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:2:0) after 1m0s
[18:38:05.353] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:2:0)
[18:38:05.353] Timer tick, asking Buildbucket for the build status
[18:38:05.904] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:05.904] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:3:0) after 1m0s
[18:39:05.938] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:3:0)
[18:39:05.938] Timer tick, asking Buildbucket for the build status
[18:39:07.008] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:07.008] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:4:0) after 1m0s
[18:40:07.094] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:4:0)
[18:40:07.094] Timer tick, asking Buildbucket for the build status
[18:40:07.269] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:07.269] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:5:0) after 1m0s
[18:41:07.270] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:5:0)
[18:41:07.270] Timer tick, asking Buildbucket for the build status
[18:41:07.670] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:07.670] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:6:0) after 1m0s
[18:42:07.689] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:6:0)
[18:42:07.689] Timer tick, asking Buildbucket for the build status
[18:42:07.918] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:07.919] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:7:0) after 1m0s
[18:43:07.959] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:7:0)
[18:43:07.959] Timer tick, asking Buildbucket for the build status
[18:43:08.198] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:08.198] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:8:0) after 1m0s
[18:44:08.308] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:8:0)
[18:44:08.308] Timer tick, asking Buildbucket for the build status
[18:44:08.432] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:08.432] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:9:0) after 1m0s
[18:45:08.485] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:9:0)
[18:45:08.485] Timer tick, asking Buildbucket for the build status
[18:45:09.206] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:09.206] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:10:0) after 1m0s
[18:46:09.339] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:10:0)
[18:46:09.339] Timer tick, asking Buildbucket for the build status
[18:46:11.479] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:11.479] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:11:0) after 1m0s
[18:47:11.584] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:11:0)
[18:47:11.584] Timer tick, asking Buildbucket for the build status
[18:47:12.411] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:12.411] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:12:0) after 1m0s
[18:48:12.574] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:12:0)
[18:48:12.574] Timer tick, asking Buildbucket for the build status
[18:48:13.330] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:13.330] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:13:0) after 1m0s
[18:49:13.367] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:13:0)
[18:49:13.367] Timer tick, asking Buildbucket for the build status
[18:49:14.269] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:14.269] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:14:0) after 1m0s
[18:50:14.354] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:14:0)
[18:50:14.354] Timer tick, asking Buildbucket for the build status
[18:50:14.894] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:14.894] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:15:0) after 1m0s
[18:51:14.961] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:15:0)
[18:51:14.961] Timer tick, asking Buildbucket for the build status
[18:51:15.175] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:15.175] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:16:0) after 1m0s
[18:52:15.257] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:16:0)
[18:52:15.257] Timer tick, asking Buildbucket for the build status
[18:52:15.579] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:15.579] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:17:0) after 1m0s
[18:53:15.599] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:17:0)
[18:53:15.599] Timer tick, asking Buildbucket for the build status
[18:53:15.724] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:15.724] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:18:0) after 1m0s
[18:54:15.860] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:18:0)
[18:54:15.860] Timer tick, asking Buildbucket for the build status
[18:54:16.374] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:16.374] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:19:0) after 1m0s
[18:55:16.356] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:19:0)
[18:55:16.356] Timer tick, asking Buildbucket for the build status
[18:55:17.718] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:17.718] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:20:0) after 1m0s
[18:56:17.724] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:20:0)
[18:56:17.724] Timer tick, asking Buildbucket for the build status
[18:56:18.169] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:18.169] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:21:0) after 1m0s
[18:57:18.208] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:21:0)
[18:57:18.208] Timer tick, asking Buildbucket for the build status
[18:57:18.412] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:18.412] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:22:0) after 1m0s
[18:58:18.446] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:22:0)
[18:58:18.446] Timer tick, asking Buildbucket for the build status
[18:58:18.628] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:18.628] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:23:0) after 1m0s
[18:59:18.759] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:23:0)
[18:59:18.759] Timer tick, asking Buildbucket for the build status
[18:59:19.108] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:19.108] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:24:0) after 1m0s
[19:00:19.248] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:24:0)
[19:00:19.248] Timer tick, asking Buildbucket for the build status
[19:00:19.373] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:19.373] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:25:0) after 1m0s
[19:01:19.397] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:25:0)
[19:01:19.398] Timer tick, asking Buildbucket for the build status
[19:01:19.754] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:19.754] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:26:0) after 1m0s
[19:02:19.860] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:26:0)
[19:02:19.860] Timer tick, asking Buildbucket for the build status
[19:02:21.303] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:21.303] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:27:0) after 1m0s
[19:03:21.342] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:27:0)
[19:03:21.342] Timer tick, asking Buildbucket for the build status
[19:03:21.450] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:21.450] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:28:0) after 1m0s
[19:04:21.531] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:28:0)
[19:04:21.531] Timer tick, asking Buildbucket for the build status
[19:04:21.764] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:21.764] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:29:0) after 1m0s
[19:05:21.928] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:29:0)
[19:05:21.928] Timer tick, asking Buildbucket for the build status
[19:05:22.102] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:22.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:30:0) after 1m0s
[19:06:22.245] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:30:0)
[19:06:22.245] Timer tick, asking Buildbucket for the build status
[19:06:23.219] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:23.219] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:31:0) after 1m0s
[19:07:23.253] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:31:0)
[19:07:23.253] Timer tick, asking Buildbucket for the build status
[19:07:23.469] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:23.469] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:32:0) after 1m0s
[19:08:23.604] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:32:0)
[19:08:23.604] Timer tick, asking Buildbucket for the build status
[19:08:24.715] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:24.715] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:33:0) after 1m0s
[19:09:24.840] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:33:0)
[19:09:24.840] Timer tick, asking Buildbucket for the build status
[19:09:26.499] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:26.499] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:34:0) after 1m0s
[19:10:26.558] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:34:0)
[19:10:26.558] Timer tick, asking Buildbucket for the build status
[19:10:26.707] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:26.707] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:35:0) after 1m0s
[19:11:26.790] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:35:0)
[19:11:26.790] Timer tick, asking Buildbucket for the build status
[19:11:26.957] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:26.957] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:36:0) after 1m0s
[19:12:27.080] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:36:0)
[19:12:27.080] Timer tick, asking Buildbucket for the build status
[19:12:27.220] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:27.220] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:37:0) after 1m0s
[19:13:27.250] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:37:0)
[19:13:27.250] Timer tick, asking Buildbucket for the build status
[19:13:28.792] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:28.792] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:38:0) after 1m0s
[19:14:28.805] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:38:0)
[19:14:28.805] Timer tick, asking Buildbucket for the build status
[19:14:29.048] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:29.049] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:39:0) after 1m0s
[19:15:29.059] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:39:0)
[19:15:29.059] Timer tick, asking Buildbucket for the build status
[19:15:29.631] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:29.631] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:40:0) after 1m0s
[19:16:29.670] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:40:0)
[19:16:29.670] Timer tick, asking Buildbucket for the build status
[19:16:30.083] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:30.083] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:41:0) after 1m0s
[19:17:30.169] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:41:0)
[19:17:30.170] Timer tick, asking Buildbucket for the build status
[19:17:30.650] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:30.650] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:42:0) after 1m0s
[19:18:30.674] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:42:0)
[19:18:30.674] Timer tick, asking Buildbucket for the build status
[19:18:31.119] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:31.119] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:43:0) after 1m0s
[19:19:31.226] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:43:0)
[19:19:31.226] Timer tick, asking Buildbucket for the build status
[19:19:31.347] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:31.347] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:44:0) after 1m0s
[19:20:31.331] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:44:0)
[19:20:31.331] Timer tick, asking Buildbucket for the build status
[19:20:31.871] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:31.871] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:45:0) after 1m0s
[19:21:31.993] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:45:0)
[19:21:31.993] Timer tick, asking Buildbucket for the build status
[19:21:32.449] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:32.450] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:46:0) after 1m0s
[19:22:32.498] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:46:0)
[19:22:32.498] Timer tick, asking Buildbucket for the build status
[19:22:33.882] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:33.882] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:47:0) after 1m0s
[19:23:33.917] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:47:0)
[19:23:33.917] Timer tick, asking Buildbucket for the build status
[19:23:34.263] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:34.263] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:48:0) after 1m0s
[19:24:34.305] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:48:0)
[19:24:34.305] Timer tick, asking Buildbucket for the build status
[19:24:34.654] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:34.654] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:49:0) after 1m0s
[19:25:34.697] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:49:0)
[19:25:34.697] Timer tick, asking Buildbucket for the build status
[19:25:34.930] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:34.930] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:50:0) after 1m0s
[19:26:34.997] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:50:0)
[19:26:34.997] Timer tick, asking Buildbucket for the build status
[19:26:37.782] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:37.782] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:51:0) after 1m0s
[19:27:37.779] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:51:0)
[19:27:37.779] Timer tick, asking Buildbucket for the build status
[19:27:48.512] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:48.512] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:52:0) after 1m0s
[19:28:48.827] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:52:0)
[19:28:48.827] Timer tick, asking Buildbucket for the build status
[19:28:49.410] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:49.410] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:53:0) after 1m0s
[19:29:49.493] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:53:0)
[19:29:49.493] Timer tick, asking Buildbucket for the build status
[19:29:49.834] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:49.834] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:54:0) after 1m0s
[19:30:49.994] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:54:0)
[19:30:49.994] Timer tick, asking Buildbucket for the build status
[19:30:50.586] Build 8906574761759848400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:50.586] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9072004824380547984:55:0) after 1m0s
[19:30:54.796] Received PubSub notification, asking Buildbucket for the build status
[19:30:55.105] Build 8906574761759848400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:30.743] Received PubSub notification, asking Buildbucket for the build status
[19:31:32.149] Build 8906574761759848400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:31:32.149] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1564428689665801",
"created_by": "project:infra",
"created_ts": "1564425424765104",
"id": "8906574761759848400",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"3144\"}, \"id\": \"cron:v1:3144\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": [{\"cron\": {\"generation\": \"3144\"}, \"id\": \"cron:v1:3144\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-1-n2ke\", \"buildername\": \"publish_tarball_dispatcher\", \"path_config\": \"generic\", \"recipe\": \"publish_tarball\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"git\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-1-n2ke\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4382-5929880\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1564428651887712",
"status": "COMPLETED",
"status_changed_ts": "1564428690278100",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9072004824380547984",
"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/8906574761759848400/+/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:4657d71d80185a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1564428690348498",
"url": "https://ci.chromium.org/b/8906574761759848400",
"utcnow_ts": "1564428692015593"
}
}
[19:31:32.149] Invocation finished in 54m29.267712806s with status SUCCEEDED