[09:37:02.313] New invocation is queued and will start shortly
[09:37:03.416] Starting the invocation (attempt 1)
[09:37:03.471] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:37:03.472] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:37:03.472] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9061529550760774768",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"5919\"},\"id\":\"cron:v1:5919\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9061529550760774768",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[09:37:04.049] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1574415423608656",
"id": "8896099488733299504",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5919\"}, \"id\": \"cron:v1:5919\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574415423844613",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9061529550760774768",
"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/8896099488733299504/+/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": "1574415423844770",
"url": "https://ci.chromium.org/b/8896099488733299504",
"utcnow_ts": "1574415424037330"
}
}
[09:37:04.049] Task URL: https://ci.chromium.org/b/8896099488733299504
[09:37:04.049] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:2:0) after 1m0s
[09:38:04.037] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:2:0)
[09:38:04.037] Timer tick, asking Buildbucket for the build status
[09:38:04.207] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:04.207] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:3:0) after 1m0s
[09:39:04.318] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:3:0)
[09:39:04.318] Timer tick, asking Buildbucket for the build status
[09:39:04.471] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:04.471] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:4:0) after 1m0s
[09:40:04.555] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:4:0)
[09:40:04.555] Timer tick, asking Buildbucket for the build status
[09:40:04.700] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:04.700] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:5:0) after 1m0s
[09:41:04.723] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:5:0)
[09:41:04.723] Timer tick, asking Buildbucket for the build status
[09:41:04.845] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:04.845] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:6:0) after 1m0s
[09:42:04.904] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:6:0)
[09:42:04.904] Timer tick, asking Buildbucket for the build status
[09:42:05.072] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:05.073] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:7:0) after 1m0s
[09:43:05.144] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:7:0)
[09:43:05.144] Timer tick, asking Buildbucket for the build status
[09:43:05.946] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:05.946] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:8:0) after 1m0s
[09:44:06.023] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:8:0)
[09:44:06.023] Timer tick, asking Buildbucket for the build status
[09:44:06.201] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:06.201] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:9:0) after 1m0s
[09:45:06.219] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:9:0)
[09:45:06.219] Timer tick, asking Buildbucket for the build status
[09:45:06.588] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:06.588] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:10:0) after 1m0s
[09:46:06.788] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:10:0)
[09:46:06.788] Timer tick, asking Buildbucket for the build status
[09:46:06.947] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:06.947] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:11:0) after 1m0s
[09:47:07.086] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:11:0)
[09:47:07.086] Timer tick, asking Buildbucket for the build status
[09:47:07.193] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:07.193] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:12:0) after 1m0s
[09:48:07.199] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:12:0)
[09:48:07.199] Timer tick, asking Buildbucket for the build status
[09:48:07.604] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:07.604] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:13:0) after 1m0s
[09:49:07.620] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:13:0)
[09:49:07.620] Timer tick, asking Buildbucket for the build status
[09:49:07.776] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:07.776] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:14:0) after 1m0s
[09:50:07.806] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:14:0)
[09:50:07.806] Timer tick, asking Buildbucket for the build status
[09:50:08.147] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:08.147] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:15:0) after 1m0s
[09:51:08.242] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:15:0)
[09:51:08.242] Timer tick, asking Buildbucket for the build status
[09:51:08.584] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:08.584] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:16:0) after 1m0s
[09:52:08.719] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:16:0)
[09:52:08.719] Timer tick, asking Buildbucket for the build status
[09:52:12.969] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:12.969] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:17:0) after 1m0s
[09:53:13.050] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:17:0)
[09:53:13.050] Timer tick, asking Buildbucket for the build status
[09:53:13.246] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:13.246] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:18:0) after 1m0s
[09:54:13.277] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:18:0)
[09:54:13.277] Timer tick, asking Buildbucket for the build status
[09:54:13.635] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:13.635] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:19:0) after 1m0s
[09:55:13.643] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:19:0)
[09:55:13.643] Timer tick, asking Buildbucket for the build status
[09:55:13.775] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:13.775] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:20:0) after 1m0s
[09:56:13.835] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:20:0)
[09:56:13.835] Timer tick, asking Buildbucket for the build status
[09:56:14.800] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:14.800] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:21:0) after 1m0s
[09:57:14.953] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:21:0)
[09:57:14.953] Timer tick, asking Buildbucket for the build status
[09:57:15.179] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:15.179] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:22:0) after 1m0s
[09:58:15.233] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:22:0)
[09:58:15.233] Timer tick, asking Buildbucket for the build status
[09:58:15.403] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:15.403] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:23:0) after 1m0s
[09:59:15.577] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:23:0)
[09:59:15.577] Timer tick, asking Buildbucket for the build status
[09:59:15.810] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:15.810] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:24:0) after 1m0s
[10:00:15.998] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:24:0)
[10:00:15.998] Timer tick, asking Buildbucket for the build status
[10:00:16.259] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:16.259] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:25:0) after 1m0s
[10:01:16.305] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:25:0)
[10:01:16.305] Timer tick, asking Buildbucket for the build status
[10:01:16.496] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:16.496] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:26:0) after 1m0s
[10:02:16.604] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:26:0)
[10:02:16.604] Timer tick, asking Buildbucket for the build status
[10:02:16.836] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:16.836] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:27:0) after 1m0s
[10:03:16.911] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:27:0)
[10:03:16.911] Timer tick, asking Buildbucket for the build status
[10:03:17.090] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:17.090] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:28:0) after 1m0s
[10:04:17.495] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:28:0)
[10:04:17.495] Timer tick, asking Buildbucket for the build status
[10:04:17.767] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:17.767] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:29:0) after 1m0s
[10:05:17.892] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:29:0)
[10:05:17.892] Timer tick, asking Buildbucket for the build status
[10:05:18.084] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:18.084] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:30:0) after 1m0s
[10:06:18.308] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:30:0)
[10:06:18.308] Timer tick, asking Buildbucket for the build status
[10:06:18.607] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:18.607] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:31:0) after 1m0s
[10:07:18.718] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:31:0)
[10:07:18.718] Timer tick, asking Buildbucket for the build status
[10:07:18.858] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:18.858] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:32:0) after 1m0s
[10:08:19.038] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:32:0)
[10:08:19.038] Timer tick, asking Buildbucket for the build status
[10:08:19.509] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:19.509] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:33:0) after 1m0s
[10:09:19.689] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:33:0)
[10:09:19.689] Timer tick, asking Buildbucket for the build status
[10:09:19.879] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:19.879] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:34:0) after 1m0s
[10:10:19.993] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:34:0)
[10:10:19.993] Timer tick, asking Buildbucket for the build status
[10:10:20.098] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:20.098] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:35:0) after 1m0s
[10:11:20.158] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:35:0)
[10:11:20.158] Timer tick, asking Buildbucket for the build status
[10:11:20.370] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:20.370] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:36:0) after 1m0s
[10:12:20.419] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:36:0)
[10:12:20.419] Timer tick, asking Buildbucket for the build status
[10:12:20.531] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:20.531] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:37:0) after 1m0s
[10:13:20.594] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:37:0)
[10:13:20.594] Timer tick, asking Buildbucket for the build status
[10:13:20.724] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:20.724] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:38:0) after 1m0s
[10:14:20.809] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:38:0)
[10:14:20.809] Timer tick, asking Buildbucket for the build status
[10:14:20.907] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:20.907] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:39:0) after 1m0s
[10:15:20.989] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:39:0)
[10:15:20.989] Timer tick, asking Buildbucket for the build status
[10:15:21.816] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:21.816] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:40:0) after 1m0s
[10:16:21.968] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:40:0)
[10:16:21.968] Timer tick, asking Buildbucket for the build status
[10:16:22.239] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:22.239] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:41:0) after 1m0s
[10:17:22.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:41:0)
[10:17:22.302] Timer tick, asking Buildbucket for the build status
[10:17:22.809] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:22.809] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:42:0) after 1m0s
[10:18:22.850] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:42:0)
[10:18:22.850] Timer tick, asking Buildbucket for the build status
[10:18:23.237] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:23.237] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:43:0) after 1m0s
[10:19:23.319] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:43:0)
[10:19:23.319] Timer tick, asking Buildbucket for the build status
[10:19:23.608] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:23.608] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:44:0) after 1m0s
[10:20:23.657] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:44:0)
[10:20:23.657] Timer tick, asking Buildbucket for the build status
[10:20:25.409] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:25.409] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:45:0) after 1m0s
[10:21:25.541] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:45:0)
[10:21:25.541] Timer tick, asking Buildbucket for the build status
[10:21:25.719] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:25.719] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:46:0) after 1m0s
[10:22:25.753] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:46:0)
[10:22:25.753] Timer tick, asking Buildbucket for the build status
[10:22:25.928] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:25.928] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:47:0) after 1m0s
[10:23:25.967] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:47:0)
[10:23:25.967] Timer tick, asking Buildbucket for the build status
[10:23:26.296] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:26.296] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:48:0) after 1m0s
[10:24:26.286] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:48:0)
[10:24:26.286] Timer tick, asking Buildbucket for the build status
[10:24:26.658] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:26.658] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:49:0) after 1m0s
[10:25:26.734] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:49:0)
[10:25:26.734] Timer tick, asking Buildbucket for the build status
[10:25:26.821] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:26.821] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:50:0) after 1m0s
[10:26:26.902] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:50:0)
[10:26:26.902] Timer tick, asking Buildbucket for the build status
[10:26:27.289] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:27.289] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:51:0) after 1m0s
[10:27:27.349] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:51:0)
[10:27:27.349] Timer tick, asking Buildbucket for the build status
[10:27:27.496] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:27.496] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:52:0) after 1m0s
[10:28:27.642] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:52:0)
[10:28:27.642] Timer tick, asking Buildbucket for the build status
[10:28:27.729] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:27.729] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:53:0) after 1m0s
[10:29:27.877] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:53:0)
[10:29:27.877] Timer tick, asking Buildbucket for the build status
[10:29:28.329] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:28.329] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:54:0) after 1m0s
[10:30:28.468] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:54:0)
[10:30:28.468] Timer tick, asking Buildbucket for the build status
[10:30:28.665] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:28.665] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:55:0) after 1m0s
[10:31:28.721] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:55:0)
[10:31:28.721] Timer tick, asking Buildbucket for the build status
[10:31:28.860] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:28.860] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:56:0) after 1m0s
[10:32:29.016] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:56:0)
[10:32:29.016] Timer tick, asking Buildbucket for the build status
[10:32:29.363] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:29.363] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:57:0) after 1m0s
[10:33:29.425] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:57:0)
[10:33:29.425] Timer tick, asking Buildbucket for the build status
[10:33:29.709] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:29.709] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:58:0) after 1m0s
[10:34:29.744] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:58:0)
[10:34:29.744] Timer tick, asking Buildbucket for the build status
[10:34:29.838] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:29.838] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:59:0) after 1m0s
[10:35:29.845] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:59:0)
[10:35:29.845] Timer tick, asking Buildbucket for the build status
[10:35:29.954] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:29.954] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:60:0) after 1m0s
[10:36:30.037] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:60:0)
[10:36:30.037] Timer tick, asking Buildbucket for the build status
[10:36:30.183] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:30.183] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:61:0) after 1m0s
[10:37:30.242] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:61:0)
[10:37:30.242] Timer tick, asking Buildbucket for the build status
[10:37:30.482] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:30.482] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:62:0) after 1m0s
[10:38:30.516] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:62:0)
[10:38:30.516] Timer tick, asking Buildbucket for the build status
[10:38:30.768] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:30.768] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:63:0) after 1m0s
[10:39:30.830] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:63:0)
[10:39:30.830] Timer tick, asking Buildbucket for the build status
[10:39:30.926] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:30.926] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:64:0) after 1m0s
[10:40:30.980] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:64:0)
[10:40:30.980] Timer tick, asking Buildbucket for the build status
[10:40:31.139] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:31.139] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:65:0) after 1m0s
[10:41:31.275] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:65:0)
[10:41:31.275] Timer tick, asking Buildbucket for the build status
[10:41:31.572] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:31.572] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:66:0) after 1m0s
[10:42:31.598] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:66:0)
[10:42:31.598] Timer tick, asking Buildbucket for the build status
[10:42:31.703] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:31.703] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:67:0) after 1m0s
[10:43:31.843] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:67:0)
[10:43:31.843] Timer tick, asking Buildbucket for the build status
[10:43:32.134] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:32.134] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:68:0) after 1m0s
[10:44:32.215] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:68:0)
[10:44:32.215] Timer tick, asking Buildbucket for the build status
[10:44:32.352] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:32.352] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:69:0) after 1m0s
[10:45:32.622] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:69:0)
[10:45:32.622] Timer tick, asking Buildbucket for the build status
[10:45:32.751] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:32.751] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:70:0) after 1m0s
[10:46:32.819] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:70:0)
[10:46:32.819] Timer tick, asking Buildbucket for the build status
[10:46:33.336] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:33.336] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:71:0) after 1m0s
[10:47:33.381] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:71:0)
[10:47:33.381] Timer tick, asking Buildbucket for the build status
[10:47:33.839] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:33.839] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:72:0) after 1m0s
[10:48:33.839] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:72:0)
[10:48:33.839] Timer tick, asking Buildbucket for the build status
[10:48:33.977] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:33.977] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:73:0) after 1m0s
[10:49:34.072] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:73:0)
[10:49:34.072] Timer tick, asking Buildbucket for the build status
[10:49:34.221] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:34.222] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:74:0) after 1m0s
[10:50:34.310] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:74:0)
[10:50:34.310] Timer tick, asking Buildbucket for the build status
[10:50:34.812] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:34.812] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:75:0) after 1m0s
[10:51:34.886] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:75:0)
[10:51:34.886] Timer tick, asking Buildbucket for the build status
[10:51:35.087] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:35.087] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:76:0) after 1m0s
[10:52:35.195] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:76:0)
[10:52:35.195] Timer tick, asking Buildbucket for the build status
[10:52:35.317] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:35.318] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:77:0) after 1m0s
[10:53:35.613] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:77:0)
[10:53:35.613] Timer tick, asking Buildbucket for the build status
[10:53:35.856] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:35.856] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:78:0) after 1m0s
[10:54:36.213] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:78:0)
[10:54:36.213] Timer tick, asking Buildbucket for the build status
[10:54:36.420] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:36.420] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:79:0) after 1m0s
[10:55:36.595] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:79:0)
[10:55:36.595] Timer tick, asking Buildbucket for the build status
[10:55:36.793] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:55:36.793] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:80:0) after 1m0s
[10:56:36.830] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:80:0)
[10:56:36.831] Timer tick, asking Buildbucket for the build status
[10:56:37.123] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:56:37.123] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:81:0) after 1m0s
[10:57:37.464] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:81:0)
[10:57:37.464] Timer tick, asking Buildbucket for the build status
[10:57:37.650] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:57:37.650] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:82:0) after 1m0s
[10:58:37.748] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:82:0)
[10:58:37.748] Timer tick, asking Buildbucket for the build status
[10:58:37.962] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:58:37.962] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:83:0) after 1m0s
[10:59:38.168] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:83:0)
[10:59:38.168] Timer tick, asking Buildbucket for the build status
[10:59:38.383] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:59:38.383] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:84:0) after 1m0s
[11:00:38.500] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:84:0)
[11:00:38.500] Timer tick, asking Buildbucket for the build status
[11:00:39.081] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:00:39.081] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:85:0) after 1m0s
[11:01:39.185] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:85:0)
[11:01:39.185] Timer tick, asking Buildbucket for the build status
[11:01:40.683] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:01:40.683] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:86:0) after 1m0s
[11:02:41.031] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:86:0)
[11:02:41.031] Timer tick, asking Buildbucket for the build status
[11:02:41.630] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:02:41.630] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:87:0) after 1m0s
[11:03:41.644] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:87:0)
[11:03:41.644] Timer tick, asking Buildbucket for the build status
[11:03:41.883] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:03:41.883] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:88:0) after 1m0s
[11:04:41.911] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:88:0)
[11:04:41.911] Timer tick, asking Buildbucket for the build status
[11:04:42.286] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:04:42.286] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:89:0) after 1m0s
[11:05:42.313] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:89:0)
[11:05:42.313] Timer tick, asking Buildbucket for the build status
[11:05:42.455] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:05:42.455] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:90:0) after 1m0s
[11:06:42.566] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:90:0)
[11:06:42.566] Timer tick, asking Buildbucket for the build status
[11:06:43.710] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:06:43.710] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:91:0) after 1m0s
[11:07:43.745] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:91:0)
[11:07:43.745] Timer tick, asking Buildbucket for the build status
[11:07:43.975] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:43.975] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:92:0) after 1m0s
[11:08:44.046] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:92:0)
[11:08:44.046] Timer tick, asking Buildbucket for the build status
[11:08:44.168] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:44.168] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:93:0) after 1m0s
[11:09:44.322] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:93:0)
[11:09:44.322] Timer tick, asking Buildbucket for the build status
[11:09:44.527] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:09:44.527] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:94:0) after 1m0s
[11:10:44.612] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:94:0)
[11:10:44.612] Timer tick, asking Buildbucket for the build status
[11:10:44.856] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:10:44.856] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:95:0) after 1m0s
[11:11:44.947] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:95:0)
[11:11:44.947] Timer tick, asking Buildbucket for the build status
[11:11:45.342] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:45.342] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:96:0) after 1m0s
[11:12:45.574] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:96:0)
[11:12:45.574] Timer tick, asking Buildbucket for the build status
[11:12:45.732] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:12:45.732] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:97:0) after 1m0s
[11:13:45.799] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:97:0)
[11:13:45.799] Timer tick, asking Buildbucket for the build status
[11:13:46.691] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:13:46.691] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:98:0) after 1m0s
[11:14:46.738] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:98:0)
[11:14:46.738] Timer tick, asking Buildbucket for the build status
[11:14:47.292] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:14:47.292] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:99:0) after 1m0s
[11:15:47.385] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:99:0)
[11:15:47.385] Timer tick, asking Buildbucket for the build status
[11:15:47.793] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:15:47.793] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:100:0) after 1m0s
[11:16:47.873] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:100:0)
[11:16:47.873] Timer tick, asking Buildbucket for the build status
[11:16:47.988] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:16:47.988] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:101:0) after 1m0s
[11:17:48.084] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:101:0)
[11:17:48.084] Timer tick, asking Buildbucket for the build status
[11:17:48.210] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:17:48.210] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:102:0) after 1m0s
[11:18:48.340] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:102:0)
[11:18:48.340] Timer tick, asking Buildbucket for the build status
[11:18:48.521] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:18:48.521] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:103:0) after 1m0s
[11:19:48.567] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:103:0)
[11:19:48.567] Timer tick, asking Buildbucket for the build status
[11:19:48.664] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:19:48.664] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:104:0) after 1m0s
[11:20:48.731] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:104:0)
[11:20:48.731] Timer tick, asking Buildbucket for the build status
[11:20:49.043] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:20:49.043] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:105:0) after 1m0s
[11:21:49.082] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:105:0)
[11:21:49.082] Timer tick, asking Buildbucket for the build status
[11:21:49.418] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:21:49.418] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:106:0) after 1m0s
[11:22:49.529] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:106:0)
[11:22:49.536] Timer tick, asking Buildbucket for the build status
[11:22:49.641] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:22:49.642] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:107:0) after 1m0s
[11:23:49.801] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:107:0)
[11:23:49.801] Timer tick, asking Buildbucket for the build status
[11:23:49.914] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:23:49.914] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:108:0) after 1m0s
[11:24:49.939] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:108:0)
[11:24:49.939] Timer tick, asking Buildbucket for the build status
[11:24:50.347] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:24:50.347] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:109:0) after 1m0s
[11:25:50.390] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:109:0)
[11:25:50.390] Timer tick, asking Buildbucket for the build status
[11:25:50.488] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:50.488] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:110:0) after 1m0s
[11:26:50.600] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:110:0)
[11:26:50.600] Timer tick, asking Buildbucket for the build status
[11:26:50.720] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:50.720] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:111:0) after 1m0s
[11:27:50.780] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:111:0)
[11:27:50.780] Timer tick, asking Buildbucket for the build status
[11:27:50.916] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:50.916] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:112:0) after 1m0s
[11:28:50.978] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:112:0)
[11:28:50.978] Timer tick, asking Buildbucket for the build status
[11:28:51.337] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:51.337] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:113:0) after 1m0s
[11:29:51.369] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:113:0)
[11:29:51.369] Timer tick, asking Buildbucket for the build status
[11:29:51.566] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:51.566] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:114:0) after 1m0s
[11:30:51.588] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:114:0)
[11:30:51.588] Timer tick, asking Buildbucket for the build status
[11:30:51.803] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:51.803] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:115:0) after 1m0s
[11:31:51.944] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:115:0)
[11:31:51.944] Timer tick, asking Buildbucket for the build status
[11:31:52.279] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:52.279] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:116:0) after 1m0s
[11:32:52.447] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:116:0)
[11:32:52.447] Timer tick, asking Buildbucket for the build status
[11:32:52.907] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:52.907] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:117:0) after 1m0s
[11:33:52.950] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:117:0)
[11:33:52.950] Timer tick, asking Buildbucket for the build status
[11:33:53.301] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:53.301] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:118:0) after 1m0s
[11:34:53.366] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:118:0)
[11:34:53.366] Timer tick, asking Buildbucket for the build status
[11:34:53.543] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:34:53.543] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:119:0) after 1m0s
[11:35:53.583] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:119:0)
[11:35:53.583] Timer tick, asking Buildbucket for the build status
[11:35:53.822] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:35:53.822] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:120:0) after 1m0s
[11:36:53.940] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:120:0)
[11:36:53.940] Timer tick, asking Buildbucket for the build status
[11:36:54.074] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:36:54.074] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:121:0) after 1m0s
[11:37:54.291] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:121:0)
[11:37:54.291] Timer tick, asking Buildbucket for the build status
[11:37:54.482] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:54.482] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:122:0) after 1m0s
[11:38:54.858] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:122:0)
[11:38:54.858] Timer tick, asking Buildbucket for the build status
[11:38:55.140] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:55.140] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:123:0) after 1m0s
[11:39:55.186] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:123:0)
[11:39:55.186] Timer tick, asking Buildbucket for the build status
[11:39:55.313] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:55.313] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:124:0) after 1m0s
[11:40:55.545] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:124:0)
[11:40:55.545] Timer tick, asking Buildbucket for the build status
[11:40:55.668] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:55.668] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:125:0) after 1m0s
[11:41:55.828] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:125:0)
[11:41:55.828] Timer tick, asking Buildbucket for the build status
[11:41:55.981] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:55.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:126:0) after 1m0s
[11:42:56.016] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:126:0)
[11:42:56.016] Timer tick, asking Buildbucket for the build status
[11:42:56.211] Build 8896099488733299504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:56.211] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:127:0) after 1m0s
[11:43:36.561] Received PubSub notification, asking Buildbucket for the build status
[11:43:36.709] Build 8896099488733299504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:56.243] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:127:0)
[11:43:56.243] Timer tick, asking Buildbucket for the build status
[11:43:56.389] Build 8896099488733299504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:56.389] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9061529550760774768:129:0) after 1m0s
[11:44:07.380] Received PubSub notification, asking Buildbucket for the build status
[11:44:08.274] Build 8896099488733299504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:44:08.274] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1574423045448408",
"created_by": "project:infra",
"created_ts": "1574415423608656",
"id": "8896099488733299504",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5919\"}, \"id\": \"cron:v1:5919\"}]}}}",
"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\": \"5919\"}, \"id\": \"cron:v1:5919\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-0-9s9r\", \"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-0-9s9r\"], \"image\": [\"chrome-xenial-19112000-160fdd580f1\"], \"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\": [\"4651-c74e250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574423015140765",
"status": "COMPLETED",
"status_changed_ts": "1574423045844745",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9061529550760774768",
"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/8896099488733299504/+/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:48ab4abf45ee1810",
"user_agent:luci-scheduler"
],
"updated_ts": "1574423045855319",
"url": "https://ci.chromium.org/b/8896099488733299504",
"utcnow_ts": "1574423048262910"
}
}
[11:44:08.274] Invocation finished in 2h7m5.983662382s with status SUCCEEDED