[00:37:03.158] New invocation is queued and will start shortly
[00:37:05.257] Starting the invocation (attempt 1)
[00:37:05.295] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:37:05.295] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:37:05.295] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9063737850925244096",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"5334\"},\"id\":\"cron:v1:5334\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9063737850925244096",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[00:37:07.073] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1572309425952195",
"id": "8898307787331294128",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5334\"}, \"id\": \"cron:v1:5334\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572309426624858",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9063737850925244096",
"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/8898307787331294128/+/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": "1572309426625034",
"url": "https://ci.chromium.org/b/8898307787331294128",
"utcnow_ts": "1572309427046083"
}
}
[00:37:07.073] Task URL: https://ci.chromium.org/b/8898307787331294128
[00:37:07.073] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:2:0) after 1m0s
[00:38:07.162] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:2:0)
[00:38:07.162] Timer tick, asking Buildbucket for the build status
[00:38:07.387] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:07.387] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:3:0) after 1m0s
[00:39:07.412] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:3:0)
[00:39:07.412] Timer tick, asking Buildbucket for the build status
[00:39:07.734] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:07.734] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:4:0) after 1m0s
[00:40:07.712] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:4:0)
[00:40:07.712] Timer tick, asking Buildbucket for the build status
[00:40:10.727] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:10.727] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:5:0) after 1m0s
[00:41:10.759] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:5:0)
[00:41:10.759] Timer tick, asking Buildbucket for the build status
[00:41:11.317] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:11.317] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:6:0) after 1m0s
[00:42:11.339] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:6:0)
[00:42:11.339] Timer tick, asking Buildbucket for the build status
[00:42:11.772] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:11.772] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:7:0) after 1m0s
[00:43:12.020] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:7:0)
[00:43:12.020] Timer tick, asking Buildbucket for the build status
[00:43:12.522] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:12.522] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:8:0) after 1m0s
[00:44:12.727] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:8:0)
[00:44:12.727] Timer tick, asking Buildbucket for the build status
[00:44:13.011] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:13.011] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:9:0) after 1m0s
[00:45:13.035] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:9:0)
[00:45:13.035] Timer tick, asking Buildbucket for the build status
[00:45:13.361] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:13.361] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:10:0) after 1m0s
[00:46:13.401] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:10:0)
[00:46:13.401] Timer tick, asking Buildbucket for the build status
[00:46:13.527] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:13.527] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:11:0) after 1m0s
[00:47:13.538] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:11:0)
[00:47:13.538] Timer tick, asking Buildbucket for the build status
[00:47:14.289] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:14.289] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:12:0) after 1m0s
[00:48:14.311] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:12:0)
[00:48:14.311] Timer tick, asking Buildbucket for the build status
[00:48:14.499] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:14.499] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:13:0) after 1m0s
[00:49:14.548] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:13:0)
[00:49:14.548] Timer tick, asking Buildbucket for the build status
[00:49:15.457] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:15.457] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:14:0) after 1m0s
[00:50:15.621] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:14:0)
[00:50:15.621] Timer tick, asking Buildbucket for the build status
[00:50:16.024] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:16.024] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:15:0) after 1m0s
[00:51:16.431] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:15:0)
[00:51:16.431] Timer tick, asking Buildbucket for the build status
[00:51:17.255] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:17.255] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:16:0) after 1m0s
[00:52:17.564] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:16:0)
[00:52:17.564] Timer tick, asking Buildbucket for the build status
[00:52:18.969] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:18.969] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:17:0) after 1m0s
[00:53:19.151] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:17:0)
[00:53:19.151] Timer tick, asking Buildbucket for the build status
[00:53:19.421] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:19.421] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:18:0) after 1m0s
[00:54:19.688] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:18:0)
[00:54:19.689] Timer tick, asking Buildbucket for the build status
[00:54:20.906] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:20.906] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:19:0) after 1m0s
[00:55:20.934] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:19:0)
[00:55:20.934] Timer tick, asking Buildbucket for the build status
[00:55:21.313] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:21.313] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:20:0) after 1m0s
[00:56:21.416] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:20:0)
[00:56:21.416] Timer tick, asking Buildbucket for the build status
[00:56:21.832] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:21.832] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:21:0) after 1m0s
[00:57:21.919] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:21:0)
[00:57:21.919] Timer tick, asking Buildbucket for the build status
[00:57:22.125] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:22.125] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:22:0) after 1m0s
[00:58:22.205] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:22:0)
[00:58:22.205] Timer tick, asking Buildbucket for the build status
[00:58:22.587] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:22.588] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:23:0) after 1m0s
[00:59:22.654] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:23:0)
[00:59:22.654] Timer tick, asking Buildbucket for the build status
[00:59:22.877] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:22.877] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:24:0) after 1m0s
[01:00:22.955] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:24:0)
[01:00:22.955] Timer tick, asking Buildbucket for the build status
[01:00:23.369] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:23.369] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:25:0) after 1m0s
[01:01:23.413] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:25:0)
[01:01:23.413] Timer tick, asking Buildbucket for the build status
[01:01:23.634] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:23.634] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:26:0) after 1m0s
[01:02:23.689] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:26:0)
[01:02:23.689] Timer tick, asking Buildbucket for the build status
[01:02:24.371] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:24.371] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:27:0) after 1m0s
[01:03:24.458] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:27:0)
[01:03:24.458] Timer tick, asking Buildbucket for the build status
[01:03:24.670] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:24.670] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:28:0) after 1m0s
[01:04:24.807] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:28:0)
[01:04:24.807] Timer tick, asking Buildbucket for the build status
[01:04:24.908] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:24.908] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:29:0) after 1m0s
[01:05:24.963] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:29:0)
[01:05:24.963] Timer tick, asking Buildbucket for the build status
[01:05:25.164] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:25.164] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:30:0) after 1m0s
[01:06:25.172] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:30:0)
[01:06:25.172] Timer tick, asking Buildbucket for the build status
[01:06:25.335] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:25.335] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:31:0) after 1m0s
[01:07:25.793] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:31:0)
[01:07:25.793] Timer tick, asking Buildbucket for the build status
[01:07:25.975] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:25.975] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:32:0) after 1m0s
[01:08:26.144] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:32:0)
[01:08:26.144] Timer tick, asking Buildbucket for the build status
[01:08:26.586] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:26.586] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:33:0) after 1m0s
[01:09:26.708] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:33:0)
[01:09:26.708] Timer tick, asking Buildbucket for the build status
[01:09:27.375] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:27.375] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:34:0) after 1m0s
[01:10:27.396] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:34:0)
[01:10:27.396] Timer tick, asking Buildbucket for the build status
[01:10:27.592] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:27.592] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:35:0) after 1m0s
[01:11:27.583] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:35:0)
[01:11:27.583] Timer tick, asking Buildbucket for the build status
[01:11:27.695] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:27.695] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:36:0) after 1m0s
[01:12:28.067] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:36:0)
[01:12:28.067] Timer tick, asking Buildbucket for the build status
[01:12:28.696] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:28.696] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:37:0) after 1m0s
[01:13:29.092] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:37:0)
[01:13:29.092] Timer tick, asking Buildbucket for the build status
[01:13:29.404] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:29.404] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:38:0) after 1m0s
[01:14:29.983] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:38:0)
[01:14:29.983] Timer tick, asking Buildbucket for the build status
[01:14:30.754] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:30.754] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:39:0) after 1m0s
[01:15:31.011] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:39:0)
[01:15:31.011] Timer tick, asking Buildbucket for the build status
[01:15:31.427] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:31.427] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:40:0) after 1m0s
[01:16:31.499] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:40:0)
[01:16:31.499] Timer tick, asking Buildbucket for the build status
[01:16:31.793] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:31.793] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:41:0) after 1m0s
[01:17:31.819] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:41:0)
[01:17:31.819] Timer tick, asking Buildbucket for the build status
[01:17:32.322] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:32.322] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:42:0) after 1m0s
[01:18:32.398] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:42:0)
[01:18:32.398] Timer tick, asking Buildbucket for the build status
[01:18:32.700] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:32.700] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:43:0) after 1m0s
[01:19:32.765] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:43:0)
[01:19:32.765] Timer tick, asking Buildbucket for the build status
[01:19:32.953] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:32.953] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:44:0) after 1m0s
[01:20:33.326] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:44:0)
[01:20:33.326] Timer tick, asking Buildbucket for the build status
[01:20:33.532] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:33.533] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:45:0) after 1m0s
[01:21:33.795] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:45:0)
[01:21:33.795] Timer tick, asking Buildbucket for the build status
[01:21:33.932] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:33.932] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:46:0) after 1m0s
[01:22:34.029] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:46:0)
[01:22:34.029] Timer tick, asking Buildbucket for the build status
[01:22:34.398] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:34.398] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:47:0) after 1m0s
[01:23:34.569] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:47:0)
[01:23:34.569] Timer tick, asking Buildbucket for the build status
[01:23:34.777] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:34.777] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:48:0) after 1m0s
[01:24:34.881] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:48:0)
[01:24:34.881] Timer tick, asking Buildbucket for the build status
[01:24:35.140] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:35.140] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:49:0) after 1m0s
[01:25:35.334] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:49:0)
[01:25:35.334] Timer tick, asking Buildbucket for the build status
[01:25:35.785] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:35.785] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:50:0) after 1m0s
[01:26:35.859] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:50:0)
[01:26:35.859] Timer tick, asking Buildbucket for the build status
[01:26:37.551] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:37.551] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:51:0) after 1m0s
[01:27:37.761] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:51:0)
[01:27:37.761] Timer tick, asking Buildbucket for the build status
[01:27:38.838] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:38.838] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:52:0) after 1m0s
[01:28:38.962] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:52:0)
[01:28:38.962] Timer tick, asking Buildbucket for the build status
[01:28:39.909] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:39.909] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:53:0) after 1m0s
[01:29:40.115] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:53:0)
[01:29:40.115] Timer tick, asking Buildbucket for the build status
[01:29:40.291] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:40.291] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:54:0) after 1m0s
[01:30:40.391] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:54:0)
[01:30:40.391] Timer tick, asking Buildbucket for the build status
[01:30:41.386] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:41.386] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:55:0) after 1m0s
[01:31:41.533] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:55:0)
[01:31:41.533] Timer tick, asking Buildbucket for the build status
[01:31:42.005] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:42.005] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:56:0) after 1m0s
[01:32:42.061] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:56:0)
[01:32:42.061] Timer tick, asking Buildbucket for the build status
[01:32:42.391] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:42.391] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:57:0) after 1m0s
[01:33:42.387] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:57:0)
[01:33:42.387] Timer tick, asking Buildbucket for the build status
[01:33:43.239] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:43.239] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:58:0) after 1m0s
[01:34:43.295] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:58:0)
[01:34:43.295] Timer tick, asking Buildbucket for the build status
[01:34:43.748] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:43.748] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:59:0) after 1m0s
[01:35:43.786] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:59:0)
[01:35:43.786] Timer tick, asking Buildbucket for the build status
[01:35:44.766] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:44.766] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:60:0) after 1m0s
[01:36:44.821] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:60:0)
[01:36:44.821] Timer tick, asking Buildbucket for the build status
[01:36:46.450] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:46.450] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:61:0) after 1m0s
[01:37:46.507] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:61:0)
[01:37:46.507] Timer tick, asking Buildbucket for the build status
[01:37:46.647] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:46.647] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:62:0) after 1m0s
[01:38:46.735] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:62:0)
[01:38:46.735] Timer tick, asking Buildbucket for the build status
[01:38:47.083] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:47.083] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:63:0) after 1m0s
[01:39:47.107] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:63:0)
[01:39:47.107] Timer tick, asking Buildbucket for the build status
[01:39:47.342] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:47.342] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:64:0) after 1m0s
[01:40:47.539] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:64:0)
[01:40:47.539] Timer tick, asking Buildbucket for the build status
[01:40:47.815] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:47.815] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:65:0) after 1m0s
[01:41:47.928] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:65:0)
[01:41:47.928] Timer tick, asking Buildbucket for the build status
[01:41:48.213] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:48.213] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:66:0) after 1m0s
[01:42:48.286] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:66:0)
[01:42:48.286] Timer tick, asking Buildbucket for the build status
[01:42:48.528] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:48.528] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:67:0) after 1m0s
[01:43:48.544] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:67:0)
[01:43:48.544] Timer tick, asking Buildbucket for the build status
[01:43:48.731] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:48.731] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:68:0) after 1m0s
[01:44:48.728] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:68:0)
[01:44:48.728] Timer tick, asking Buildbucket for the build status
[01:44:48.885] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:48.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:69:0) after 1m0s
[01:45:48.948] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:69:0)
[01:45:48.948] Timer tick, asking Buildbucket for the build status
[01:45:49.246] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:49.246] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:70:0) after 1m0s
[01:46:49.284] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:70:0)
[01:46:49.284] Timer tick, asking Buildbucket for the build status
[01:46:49.680] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:49.680] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:71:0) after 1m0s
[01:47:49.702] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:71:0)
[01:47:49.702] Timer tick, asking Buildbucket for the build status
[01:47:49.827] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:49.827] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:72:0) after 1m0s
[01:48:50.275] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:72:0)
[01:48:50.275] Timer tick, asking Buildbucket for the build status
[01:48:50.471] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:50.471] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:73:0) after 1m0s
[01:49:50.738] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:73:0)
[01:49:50.738] Timer tick, asking Buildbucket for the build status
[01:49:50.948] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:50.948] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:74:0) after 1m0s
[01:50:51.143] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:74:0)
[01:50:51.143] Timer tick, asking Buildbucket for the build status
[01:50:51.272] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:51.272] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:75:0) after 1m0s
[01:51:51.556] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:75:0)
[01:51:51.556] Timer tick, asking Buildbucket for the build status
[01:51:51.713] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:51.713] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:76:0) after 1m0s
[01:52:52.282] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:76:0)
[01:52:52.282] Timer tick, asking Buildbucket for the build status
[01:52:53.573] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:53.574] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:77:0) after 1m0s
[01:53:53.956] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:77:0)
[01:53:53.956] Timer tick, asking Buildbucket for the build status
[01:53:54.407] Build 8898307787331294128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:54.407] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:78:0) after 1m0s
[01:54:34.986] Received PubSub notification, asking Buildbucket for the build status
[01:54:35.156] Build 8898307787331294128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:54.487] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:78:0)
[01:54:54.487] Timer tick, asking Buildbucket for the build status
[01:54:54.739] Build 8898307787331294128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:54.739] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9063737850925244096:80:0) after 1m0s
[01:55:13.153] Received PubSub notification, asking Buildbucket for the build status
[01:55:13.461] Build 8898307787331294128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:55:13.461] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1572314112527169",
"created_by": "project:infra",
"created_ts": "1572309425952195",
"id": "8898307787331294128",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5334\"}, \"id\": \"cron:v1:5334\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5334\"}, \"id\": \"cron:v1:5334\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-1-d09k\", \"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-d09k\"], \"image\": [\"chrome-xenial-19102400-d35fedf5a0d\"], \"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\": [\"4594-a77d3be\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1572314073466214",
"status": "COMPLETED",
"status_changed_ts": "1572314112882923",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9063737850925244096",
"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/8898307787331294128/+/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:482dc422a789a410",
"user_agent:luci-scheduler"
],
"updated_ts": "1572314112888449",
"url": "https://ci.chromium.org/b/8898307787331294128",
"utcnow_ts": "1572314113433604"
}
}
[01:55:13.461] Invocation finished in 1h18m10.319437914s with status SUCCEEDED