[00:17:04.574] New invocation is queued and will start shortly
[00:17:05.907] Starting the invocation (attempt 1)
[00:17:05.953] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:17:05.953] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:17:05.954] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9033117333095632656",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"13443\"},\"id\":\"cron:v1:13443\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9033117333095632656",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[00:17:06.635] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1601511426086442",
"id": "8867687270838612352",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"13443\"}, \"id\": \"cron:v1:13443\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601511426449233",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9033117333095632656",
"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/8867687270838612352/+/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": "1601511426449392",
"url": "https://ci.chromium.org/b/8867687270838612352",
"utcnow_ts": "1601511426625927"
}
}
[00:17:06.636] Task URL: https://ci.chromium.org/b/8867687270838612352
[00:17:06.636] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:2:0) after 1m0s
[00:18:06.695] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:2:0)
[00:18:06.695] Timer tick, asking Buildbucket for the build status
[00:18:07.142] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:07.142] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:3:0) after 1m0s
[00:19:07.203] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:3:0)
[00:19:07.203] Timer tick, asking Buildbucket for the build status
[00:19:07.301] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:07.301] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:4:0) after 1m0s
[00:20:07.359] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:4:0)
[00:20:07.359] Timer tick, asking Buildbucket for the build status
[00:20:07.529] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:07.529] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:5:0) after 1m0s
[00:21:07.604] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:5:0)
[00:21:07.604] Timer tick, asking Buildbucket for the build status
[00:21:07.705] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:07.705] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:6:0) after 1m0s
[00:22:07.739] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:6:0)
[00:22:07.739] Timer tick, asking Buildbucket for the build status
[00:22:09.899] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:09.899] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:7:0) after 1m0s
[00:23:09.921] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:7:0)
[00:23:09.921] Timer tick, asking Buildbucket for the build status
[00:23:10.025] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:10.025] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:8:0) after 1m0s
[00:24:10.030] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:8:0)
[00:24:10.030] Timer tick, asking Buildbucket for the build status
[00:24:10.160] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:10.160] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:9:0) after 1m0s
[00:25:10.267] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:9:0)
[00:25:10.267] Timer tick, asking Buildbucket for the build status
[00:25:10.376] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:10.376] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:10:0) after 1m0s
[00:26:10.496] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:10:0)
[00:26:10.496] Timer tick, asking Buildbucket for the build status
[00:26:10.727] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:10.727] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:11:0) after 1m0s
[00:27:10.815] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:11:0)
[00:27:10.815] Timer tick, asking Buildbucket for the build status
[00:27:10.965] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:10.965] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:12:0) after 1m0s
[00:28:11.013] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:12:0)
[00:28:11.013] Timer tick, asking Buildbucket for the build status
[00:28:11.146] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:11.146] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:13:0) after 1m0s
[00:29:11.229] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:13:0)
[00:29:11.229] Timer tick, asking Buildbucket for the build status
[00:29:11.494] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:11.494] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:14:0) after 1m0s
[00:30:11.514] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:14:0)
[00:30:11.514] Timer tick, asking Buildbucket for the build status
[00:30:11.602] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:11.602] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:15:0) after 1m0s
[00:31:11.679] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:15:0)
[00:31:11.679] Timer tick, asking Buildbucket for the build status
[00:31:11.801] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:11.801] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:16:0) after 1m0s
[00:32:11.829] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:16:0)
[00:32:11.829] Timer tick, asking Buildbucket for the build status
[00:32:12.286] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:12.286] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:17:0) after 1m0s
[00:33:12.337] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:17:0)
[00:33:12.337] Timer tick, asking Buildbucket for the build status
[00:33:12.678] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:12.678] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:18:0) after 1m0s
[00:34:12.747] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:18:0)
[00:34:12.747] Timer tick, asking Buildbucket for the build status
[00:34:12.834] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:12.834] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:19:0) after 1m0s
[00:35:12.945] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:19:0)
[00:35:12.945] Timer tick, asking Buildbucket for the build status
[00:35:13.035] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:13.035] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:20:0) after 1m0s
[00:36:13.012] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:20:0)
[00:36:13.012] Timer tick, asking Buildbucket for the build status
[00:36:13.121] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:13.121] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:21:0) after 1m0s
[00:37:13.141] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:21:0)
[00:37:13.141] Timer tick, asking Buildbucket for the build status
[00:37:13.249] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:13.249] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:22:0) after 1m0s
[00:38:13.346] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:22:0)
[00:38:13.346] Timer tick, asking Buildbucket for the build status
[00:38:13.504] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:13.504] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:23:0) after 1m0s
[00:39:13.500] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:23:0)
[00:39:13.500] Timer tick, asking Buildbucket for the build status
[00:39:15.061] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:15.061] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:24:0) after 1m0s
[00:40:15.146] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:24:0)
[00:40:15.146] Timer tick, asking Buildbucket for the build status
[00:40:15.835] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:15.835] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:25:0) after 1m0s
[00:41:15.863] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:25:0)
[00:41:15.863] Timer tick, asking Buildbucket for the build status
[00:41:16.087] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:16.087] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:26:0) after 1m0s
[00:42:16.107] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:26:0)
[00:42:16.107] Timer tick, asking Buildbucket for the build status
[00:42:16.261] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:16.261] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:27:0) after 1m0s
[00:43:16.310] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:27:0)
[00:43:16.310] Timer tick, asking Buildbucket for the build status
[00:43:16.397] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:16.397] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:28:0) after 1m0s
[00:44:16.445] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:28:0)
[00:44:16.445] Timer tick, asking Buildbucket for the build status
[00:44:16.548] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:16.548] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:29:0) after 1m0s
[00:45:16.532] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:29:0)
[00:45:16.532] Timer tick, asking Buildbucket for the build status
[00:45:16.677] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:16.677] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:30:0) after 1m0s
[00:46:16.736] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:30:0)
[00:46:16.736] Timer tick, asking Buildbucket for the build status
[00:46:16.984] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:16.984] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:31:0) after 1m0s
[00:47:17.137] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:31:0)
[00:47:17.137] Timer tick, asking Buildbucket for the build status
[00:47:17.242] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:17.242] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:32:0) after 1m0s
[00:48:17.283] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:32:0)
[00:48:17.283] Timer tick, asking Buildbucket for the build status
[00:48:17.612] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:17.612] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:33:0) after 1m0s
[00:49:17.655] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:33:0)
[00:49:17.655] Timer tick, asking Buildbucket for the build status
[00:49:19.256] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:19.256] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:34:0) after 1m0s
[00:50:19.427] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:34:0)
[00:50:19.427] Timer tick, asking Buildbucket for the build status
[00:50:19.597] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:19.597] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:35:0) after 1m0s
[00:51:19.612] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:35:0)
[00:51:19.612] Timer tick, asking Buildbucket for the build status
[00:51:19.720] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:19.720] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:36:0) after 1m0s
[00:52:19.740] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:36:0)
[00:52:19.740] Timer tick, asking Buildbucket for the build status
[00:52:19.915] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:19.915] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:37:0) after 1m0s
[00:53:19.934] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:37:0)
[00:53:19.934] Timer tick, asking Buildbucket for the build status
[00:53:20.005] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:20.005] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:38:0) after 1m0s
[00:54:20.048] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:38:0)
[00:54:20.048] Timer tick, asking Buildbucket for the build status
[00:54:20.383] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:20.383] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:39:0) after 1m0s
[00:55:20.427] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:39:0)
[00:55:20.427] Timer tick, asking Buildbucket for the build status
[00:55:20.502] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:20.502] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:40:0) after 1m0s
[00:56:20.546] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:40:0)
[00:56:20.546] Timer tick, asking Buildbucket for the build status
[00:56:20.623] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:20.623] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:41:0) after 1m0s
[00:57:20.669] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:41:0)
[00:57:20.669] Timer tick, asking Buildbucket for the build status
[00:57:20.773] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:20.773] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:42:0) after 1m0s
[00:58:20.827] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:42:0)
[00:58:20.827] Timer tick, asking Buildbucket for the build status
[00:58:20.902] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:20.902] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:43:0) after 1m0s
[00:59:20.932] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:43:0)
[00:59:20.932] Timer tick, asking Buildbucket for the build status
[00:59:21.012] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:21.012] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:44:0) after 1m0s
[01:00:21.095] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:44:0)
[01:00:21.095] Timer tick, asking Buildbucket for the build status
[01:00:21.216] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:21.216] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:45:0) after 1m0s
[01:01:21.299] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:45:0)
[01:01:21.299] Timer tick, asking Buildbucket for the build status
[01:01:21.380] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:21.380] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:46:0) after 1m0s
[01:02:21.389] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:46:0)
[01:02:21.389] Timer tick, asking Buildbucket for the build status
[01:02:21.519] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:21.519] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:47:0) after 1m0s
[01:03:21.514] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:47:0)
[01:03:21.514] Timer tick, asking Buildbucket for the build status
[01:03:21.592] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:21.592] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:48:0) after 1m0s
[01:04:21.613] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:48:0)
[01:04:21.613] Timer tick, asking Buildbucket for the build status
[01:04:21.693] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:21.693] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:49:0) after 1m0s
[01:05:21.722] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:49:0)
[01:05:21.722] Timer tick, asking Buildbucket for the build status
[01:05:21.859] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:21.859] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:50:0) after 1m0s
[01:06:21.925] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:50:0)
[01:06:21.925] Timer tick, asking Buildbucket for the build status
[01:06:22.018] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:22.018] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:51:0) after 1m0s
[01:07:22.220] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:51:0)
[01:07:22.220] Timer tick, asking Buildbucket for the build status
[01:07:22.408] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:22.408] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:52:0) after 1m0s
[01:08:22.480] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:52:0)
[01:08:22.480] Timer tick, asking Buildbucket for the build status
[01:08:22.561] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:22.561] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:53:0) after 1m0s
[01:09:22.777] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:53:0)
[01:09:22.777] Timer tick, asking Buildbucket for the build status
[01:09:22.862] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:22.862] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:54:0) after 1m0s
[01:10:22.913] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:54:0)
[01:10:22.913] Timer tick, asking Buildbucket for the build status
[01:10:23.010] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:23.010] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:55:0) after 1m0s
[01:11:23.034] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:55:0)
[01:11:23.035] Timer tick, asking Buildbucket for the build status
[01:11:23.419] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:23.419] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:56:0) after 1m0s
[01:12:23.465] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:56:0)
[01:12:23.465] Timer tick, asking Buildbucket for the build status
[01:12:23.584] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:23.584] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:57:0) after 1m0s
[01:13:23.654] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:57:0)
[01:13:23.654] Timer tick, asking Buildbucket for the build status
[01:13:23.789] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:23.789] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:58:0) after 1m0s
[01:14:23.815] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:58:0)
[01:14:23.815] Timer tick, asking Buildbucket for the build status
[01:14:24.080] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:24.080] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:59:0) after 1m0s
[01:15:24.099] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:59:0)
[01:15:24.099] Timer tick, asking Buildbucket for the build status
[01:15:24.176] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:24.176] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:60:0) after 1m0s
[01:16:24.165] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:60:0)
[01:16:24.165] Timer tick, asking Buildbucket for the build status
[01:16:24.251] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:24.251] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:61:0) after 1m0s
[01:17:24.335] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:61:0)
[01:17:24.335] Timer tick, asking Buildbucket for the build status
[01:17:25.462] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:25.462] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:62:0) after 1m0s
[01:18:25.439] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:62:0)
[01:18:25.439] Timer tick, asking Buildbucket for the build status
[01:18:25.582] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:25.582] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:63:0) after 1m0s
[01:19:25.823] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:63:0)
[01:19:25.823] Timer tick, asking Buildbucket for the build status
[01:19:25.988] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:25.988] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:64:0) after 1m0s
[01:20:26.069] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:64:0)
[01:20:26.069] Timer tick, asking Buildbucket for the build status
[01:20:26.146] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:26.146] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:65:0) after 1m0s
[01:21:26.217] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:65:0)
[01:21:26.217] Timer tick, asking Buildbucket for the build status
[01:21:26.302] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:26.302] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:66:0) after 1m0s
[01:22:26.401] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:66:0)
[01:22:26.401] Timer tick, asking Buildbucket for the build status
[01:22:26.503] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:26.503] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:67:0) after 1m0s
[01:23:26.489] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:67:0)
[01:23:26.489] Timer tick, asking Buildbucket for the build status
[01:23:26.573] Build 8867687270838612352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:26.573] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9033117333095632656:68:0) after 1m0s
[01:23:27.654] Received PubSub notification, asking Buildbucket for the build status
[01:23:27.742] Build 8867687270838612352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:04.477] Received PubSub notification, asking Buildbucket for the build status
[01:24:04.575] Build 8867687270838612352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:24:04.575] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1601515443092400",
"created_by": "project:infra",
"created_ts": "1601511426086442",
"id": "8867687270838612352",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"13443\"}, \"id\": \"cron:v1:13443\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_v2\", \"builder_4d1db109d30b9f0068cf0464722c703e05f788cd82e2d29876a9483818e7c333_v2\", \"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"builder_ca1dd8081634cfdf63cf6543806b7419b3e097f184142241b02f8854d1768d0b_v2\", \"builder_d09004ff56ad3d259bfb1375112387cace64bb5a4afee3da7ed4cf5892d6b500_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-0-r27e\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"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\": [\"5564-e41b668\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601515406800263",
"status": "COMPLETED",
"status_changed_ts": "1601515443851150",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9033117333095632656",
"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/8867687270838612352/+/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:4efa56929850ba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1601515443915374",
"url": "https://ci.chromium.org/b/8867687270838612352",
"utcnow_ts": "1601515444557898"
}
}
[01:24:04.575] Invocation finished in 1h7m0.025648309s with status SUCCEEDED