[15:37:03.407] New invocation is queued and will start shortly
[15:37:04.590] Starting the invocation (attempt 1)
[15:37:04.625] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:37:04.625] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:37:04.625] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9021463041216676464",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"16533\"},\"id\":\"cron:v1:16533\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9021463041216676464",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[15:37:05.059] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1612625824699170",
"id": "8856032979198230320",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"16533\"}, \"id\": \"cron:v1:16533\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612625824917628",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9021463041216676464",
"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/8856032979198230320/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:publish_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612625824917741",
"url": "https://ci.chromium.org/b/8856032979198230320",
"utcnow_ts": "1612625825053560"
}
}
[15:37:05.059] Task URL: https://ci.chromium.org/b/8856032979198230320
[15:37:05.059] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:2:0) after 1m0s
[15:38:05.092] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:2:0)
[15:38:05.092] Timer tick, asking Buildbucket for the build status
[15:38:05.206] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:05.206] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:3:0) after 1m0s
[15:39:05.255] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:3:0)
[15:39:05.255] Timer tick, asking Buildbucket for the build status
[15:39:06.235] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:06.235] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:4:0) after 1m0s
[15:40:06.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:4:0)
[15:40:06.302] Timer tick, asking Buildbucket for the build status
[15:40:06.895] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:06.895] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:5:0) after 1m0s
[15:41:06.953] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:5:0)
[15:41:06.953] Timer tick, asking Buildbucket for the build status
[15:41:07.420] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:07.420] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:6:0) after 1m0s
[15:42:07.441] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:6:0)
[15:42:07.441] Timer tick, asking Buildbucket for the build status
[15:42:07.660] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:07.660] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:7:0) after 1m0s
[15:43:07.726] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:7:0)
[15:43:07.726] Timer tick, asking Buildbucket for the build status
[15:43:08.015] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:08.015] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:8:0) after 1m0s
[15:44:08.095] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:8:0)
[15:44:08.095] Timer tick, asking Buildbucket for the build status
[15:44:08.196] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:08.196] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:9:0) after 1m0s
[15:45:08.269] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:9:0)
[15:45:08.269] Timer tick, asking Buildbucket for the build status
[15:45:08.740] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:08.740] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:10:0) after 1m0s
[15:46:08.871] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:10:0)
[15:46:08.871] Timer tick, asking Buildbucket for the build status
[15:46:08.936] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:08.936] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:11:0) after 1m0s
[15:47:09.090] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:11:0)
[15:47:09.090] Timer tick, asking Buildbucket for the build status
[15:47:09.226] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:09.226] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:12:0) after 1m0s
[15:48:09.236] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:12:0)
[15:48:09.236] Timer tick, asking Buildbucket for the build status
[15:48:09.452] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:09.452] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:13:0) after 1m0s
[15:49:09.559] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:13:0)
[15:49:09.559] Timer tick, asking Buildbucket for the build status
[15:49:09.694] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:09.694] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:14:0) after 1m0s
[15:50:09.967] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:14:0)
[15:50:09.967] Timer tick, asking Buildbucket for the build status
[15:50:10.200] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:10.200] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:15:0) after 1m0s
[15:51:10.468] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:15:0)
[15:51:10.468] Timer tick, asking Buildbucket for the build status
[15:51:10.538] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:51:10.538] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:16:0) after 1m0s
[15:52:10.620] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:16:0)
[15:52:10.620] Timer tick, asking Buildbucket for the build status
[15:52:10.754] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:52:10.754] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:17:0) after 1m0s
[15:53:10.976] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:17:0)
[15:53:10.976] Timer tick, asking Buildbucket for the build status
[15:53:11.981] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:53:11.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:18:0) after 1m0s
[15:54:12.004] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:18:0)
[15:54:12.004] Timer tick, asking Buildbucket for the build status
[15:54:12.094] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:54:12.094] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:19:0) after 1m0s
[15:55:12.140] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:19:0)
[15:55:12.140] Timer tick, asking Buildbucket for the build status
[15:55:12.299] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:12.299] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:20:0) after 1m0s
[15:56:12.433] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:20:0)
[15:56:12.433] Timer tick, asking Buildbucket for the build status
[15:56:12.517] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:12.517] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:21:0) after 1m0s
[15:57:12.627] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:21:0)
[15:57:12.627] Timer tick, asking Buildbucket for the build status
[15:57:12.744] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:12.744] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:22:0) after 1m0s
[15:58:12.800] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:22:0)
[15:58:12.800] Timer tick, asking Buildbucket for the build status
[15:58:13.062] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:58:13.062] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:23:0) after 1m0s
[15:59:13.146] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:23:0)
[15:59:13.146] Timer tick, asking Buildbucket for the build status
[15:59:13.239] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:13.239] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:24:0) after 1m0s
[16:00:13.311] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:24:0)
[16:00:13.311] Timer tick, asking Buildbucket for the build status
[16:00:13.432] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:13.432] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:25:0) after 1m0s
[16:01:13.473] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:25:0)
[16:01:13.473] Timer tick, asking Buildbucket for the build status
[16:01:13.552] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:13.552] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:26:0) after 1m0s
[16:02:13.567] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:26:0)
[16:02:13.567] Timer tick, asking Buildbucket for the build status
[16:02:13.704] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:13.704] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:27:0) after 1m0s
[16:03:13.830] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:27:0)
[16:03:13.830] Timer tick, asking Buildbucket for the build status
[16:03:13.942] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:03:13.942] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:28:0) after 1m0s
[16:04:13.930] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:28:0)
[16:04:13.930] Timer tick, asking Buildbucket for the build status
[16:04:14.010] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:04:14.010] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:29:0) after 1m0s
[16:05:14.402] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:29:0)
[16:05:14.402] Timer tick, asking Buildbucket for the build status
[16:05:14.503] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:14.504] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:30:0) after 1m0s
[16:06:14.715] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:30:0)
[16:06:14.715] Timer tick, asking Buildbucket for the build status
[16:06:14.787] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:14.787] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:31:0) after 1m0s
[16:07:15.003] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:31:0)
[16:07:15.003] Timer tick, asking Buildbucket for the build status
[16:07:15.431] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:15.431] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:32:0) after 1m0s
[16:08:15.525] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:32:0)
[16:08:15.525] Timer tick, asking Buildbucket for the build status
[16:08:15.646] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:15.646] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:33:0) after 1m0s
[16:09:15.683] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:33:0)
[16:09:15.683] Timer tick, asking Buildbucket for the build status
[16:09:15.757] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:15.757] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:34:0) after 1m0s
[16:10:15.856] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:34:0)
[16:10:15.857] Timer tick, asking Buildbucket for the build status
[16:10:16.748] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:16.748] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:35:0) after 1m0s
[16:11:16.785] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:35:0)
[16:11:16.785] Timer tick, asking Buildbucket for the build status
[16:11:16.882] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:16.882] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:36:0) after 1m0s
[16:12:16.946] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:36:0)
[16:12:16.946] Timer tick, asking Buildbucket for the build status
[16:12:17.210] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:17.210] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:37:0) after 1m0s
[16:13:17.401] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:37:0)
[16:13:17.401] Timer tick, asking Buildbucket for the build status
[16:13:17.467] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:17.467] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:38:0) after 1m0s
[16:14:17.623] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:38:0)
[16:14:17.623] Timer tick, asking Buildbucket for the build status
[16:14:17.865] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:17.865] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:39:0) after 1m0s
[16:15:17.847] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:39:0)
[16:15:17.847] Timer tick, asking Buildbucket for the build status
[16:15:17.934] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:17.934] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:40:0) after 1m0s
[16:16:17.953] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:40:0)
[16:16:17.953] Timer tick, asking Buildbucket for the build status
[16:16:18.102] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:18.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:41:0) after 1m0s
[16:17:18.793] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:41:0)
[16:17:18.793] Timer tick, asking Buildbucket for the build status
[16:17:18.890] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:18.890] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:42:0) after 1m0s
[16:18:19.301] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:42:0)
[16:18:19.301] Timer tick, asking Buildbucket for the build status
[16:18:19.575] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:19.575] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:43:0) after 1m0s
[16:19:19.799] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:43:0)
[16:19:19.799] Timer tick, asking Buildbucket for the build status
[16:19:19.949] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:19.949] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:44:0) after 1m0s
[16:20:20.300] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:44:0)
[16:20:20.300] Timer tick, asking Buildbucket for the build status
[16:20:20.391] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:20.391] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:45:0) after 1m0s
[16:21:20.531] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:45:0)
[16:21:20.531] Timer tick, asking Buildbucket for the build status
[16:21:20.794] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:20.794] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:46:0) after 1m0s
[16:22:20.837] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:46:0)
[16:22:20.837] Timer tick, asking Buildbucket for the build status
[16:22:21.305] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:21.305] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:47:0) after 1m0s
[16:23:21.324] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:47:0)
[16:23:21.324] Timer tick, asking Buildbucket for the build status
[16:23:21.536] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:21.536] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:48:0) after 1m0s
[16:24:21.564] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:48:0)
[16:24:21.564] Timer tick, asking Buildbucket for the build status
[16:24:21.666] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:21.666] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:49:0) after 1m0s
[16:25:21.689] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:49:0)
[16:25:21.689] Timer tick, asking Buildbucket for the build status
[16:25:21.919] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:21.919] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:50:0) after 1m0s
[16:26:21.947] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:50:0)
[16:26:21.947] Timer tick, asking Buildbucket for the build status
[16:26:22.060] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:22.060] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:51:0) after 1m0s
[16:27:24.384] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:51:0)
[16:27:24.384] Timer tick, asking Buildbucket for the build status
[16:27:24.480] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:24.480] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:52:0) after 1m0s
[16:28:24.906] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:52:0)
[16:28:24.906] Timer tick, asking Buildbucket for the build status
[16:28:25.035] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:25.035] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:53:0) after 1m0s
[16:29:25.601] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:53:0)
[16:29:25.601] Timer tick, asking Buildbucket for the build status
[16:29:25.673] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:25.673] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:54:0) after 1m0s
[16:30:26.034] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:54:0)
[16:30:26.034] Timer tick, asking Buildbucket for the build status
[16:30:26.161] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:26.161] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:55:0) after 1m0s
[16:31:26.395] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:55:0)
[16:31:26.395] Timer tick, asking Buildbucket for the build status
[16:31:26.706] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:26.706] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:56:0) after 1m0s
[16:32:26.967] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:56:0)
[16:32:26.967] Timer tick, asking Buildbucket for the build status
[16:32:27.116] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:27.117] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:57:0) after 1m0s
[16:33:27.376] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:57:0)
[16:33:27.376] Timer tick, asking Buildbucket for the build status
[16:33:27.798] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:27.798] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:58:0) after 1m0s
[16:34:28.055] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:58:0)
[16:34:28.055] Timer tick, asking Buildbucket for the build status
[16:34:28.496] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:28.496] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:59:0) after 1m0s
[16:35:28.548] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:59:0)
[16:35:28.548] Timer tick, asking Buildbucket for the build status
[16:35:28.644] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:28.644] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:60:0) after 1m0s
[16:36:28.813] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:60:0)
[16:36:28.813] Timer tick, asking Buildbucket for the build status
[16:36:28.898] Build 8856032979198230320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:28.898] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:61:0) after 1m0s
[16:36:48.974] Received PubSub notification, asking Buildbucket for the build status
[16:36:49.120] Build 8856032979198230320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:29.003] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:61:0)
[16:37:29.003] Timer tick, asking Buildbucket for the build status
[16:37:29.217] Build 8856032979198230320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:29.217] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9021463041216676464:63:0) after 1m0s
[16:37:36.213] Received PubSub notification, asking Buildbucket for the build status
[16:37:36.352] Build 8856032979198230320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:37:36.352] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1612629455056114",
"created_by": "project:infra",
"created_ts": "1612625824699170",
"id": "8856032979198230320",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"16533\"}, \"id\": \"cron:v1:16533\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"builder_ca1dd8081634cfdf63cf6543806b7419b3e097f184142241b02f8854d1768d0b_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-7j02\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612629406758548",
"status": "COMPLETED",
"status_changed_ts": "1612629455870956",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9021463041216676464",
"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/8856032979198230320/+/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/chromium/tools/build",
"swarming_task_id:5190cee457dba210",
"user_agent:luci-scheduler"
],
"updated_ts": "1612629455879137",
"url": "https://ci.chromium.org/b/8856032979198230320",
"utcnow_ts": "1612629456301360"
}
}
[16:37:36.352] Invocation finished in 1h0m32.961042263s with status SUCCEEDED