[21:37:02.402] New invocation is queued and will start shortly
[21:37:03.587] Starting the invocation (attempt 1)
[21:37:03.626] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:37:03.626] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:37:03.626] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9031949641137306112",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"13755\"},\"id\":\"cron:v1:13755\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9031949641137306112",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[21:37:04.795] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1602625023863231",
"id": "8866519578935599552",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"13755\"}, \"id\": \"cron:v1:13755\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602625024419761",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9031949641137306112",
"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/8866519578935599552/+/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": "1602625024419886",
"url": "https://ci.chromium.org/b/8866519578935599552",
"utcnow_ts": "1602625024771706"
}
}
[21:37:04.795] Task URL: https://ci.chromium.org/b/8866519578935599552
[21:37:04.795] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:2:0) after 1m0s
[21:38:04.926] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:2:0)
[21:38:04.926] Timer tick, asking Buildbucket for the build status
[21:38:05.049] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:05.049] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:3:0) after 1m0s
[21:39:05.073] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:3:0)
[21:39:05.073] Timer tick, asking Buildbucket for the build status
[21:39:05.282] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:05.282] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:4:0) after 1m0s
[21:40:05.317] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:4:0)
[21:40:05.317] Timer tick, asking Buildbucket for the build status
[21:40:05.404] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:05.404] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:5:0) after 1m0s
[21:41:05.598] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:5:0)
[21:41:05.598] Timer tick, asking Buildbucket for the build status
[21:41:05.938] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:05.938] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:6:0) after 1m0s
[21:42:06.115] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:6:0)
[21:42:06.115] Timer tick, asking Buildbucket for the build status
[21:42:06.289] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:06.290] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:7:0) after 1m0s
[21:43:06.367] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:7:0)
[21:43:06.367] Timer tick, asking Buildbucket for the build status
[21:43:06.553] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:06.553] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:8:0) after 1m0s
[21:44:06.606] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:8:0)
[21:44:06.607] Timer tick, asking Buildbucket for the build status
[21:44:06.830] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:06.830] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:9:0) after 1m0s
[21:45:06.934] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:9:0)
[21:45:06.934] Timer tick, asking Buildbucket for the build status
[21:45:07.102] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:07.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:10:0) after 1m0s
[21:46:07.120] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:10:0)
[21:46:07.120] Timer tick, asking Buildbucket for the build status
[21:46:07.219] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:07.219] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:11:0) after 1m0s
[21:47:07.379] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:11:0)
[21:47:07.379] Timer tick, asking Buildbucket for the build status
[21:47:07.505] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:07.505] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:12:0) after 1m0s
[21:48:07.612] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:12:0)
[21:48:07.612] Timer tick, asking Buildbucket for the build status
[21:48:07.763] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:07.763] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:13:0) after 1m0s
[21:49:07.805] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:13:0)
[21:49:07.805] Timer tick, asking Buildbucket for the build status
[21:49:07.912] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:07.912] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:14:0) after 1m0s
[21:50:07.975] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:14:0)
[21:50:07.976] Timer tick, asking Buildbucket for the build status
[21:50:08.068] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:08.068] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:15:0) after 1m0s
[21:51:08.094] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:15:0)
[21:51:08.094] Timer tick, asking Buildbucket for the build status
[21:51:08.194] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:08.194] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:16:0) after 1m0s
[21:52:08.301] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:16:0)
[21:52:08.301] Timer tick, asking Buildbucket for the build status
[21:52:08.391] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:08.391] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:17:0) after 1m0s
[21:53:08.476] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:17:0)
[21:53:08.476] Timer tick, asking Buildbucket for the build status
[21:53:09.332] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:09.332] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:18:0) after 1m0s
[21:54:09.465] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:18:0)
[21:54:09.465] Timer tick, asking Buildbucket for the build status
[21:54:09.551] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:09.551] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:19:0) after 1m0s
[21:55:09.607] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:19:0)
[21:55:09.607] Timer tick, asking Buildbucket for the build status
[21:55:09.692] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:09.692] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:20:0) after 1m0s
[21:56:09.780] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:20:0)
[21:56:09.780] Timer tick, asking Buildbucket for the build status
[21:56:09.848] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:09.848] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:21:0) after 1m0s
[21:57:10.097] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:21:0)
[21:57:10.097] Timer tick, asking Buildbucket for the build status
[21:57:10.189] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:10.190] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:22:0) after 1m0s
[21:58:10.275] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:22:0)
[21:58:10.275] Timer tick, asking Buildbucket for the build status
[21:58:10.384] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:10.385] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:23:0) after 1m0s
[21:59:10.740] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:23:0)
[21:59:10.740] Timer tick, asking Buildbucket for the build status
[21:59:10.802] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:10.802] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:24:0) after 1m0s
[22:00:11.438] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:24:0)
[22:00:11.438] Timer tick, asking Buildbucket for the build status
[22:00:11.580] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:11.580] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:25:0) after 1m0s
[22:01:11.635] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:25:0)
[22:01:11.635] Timer tick, asking Buildbucket for the build status
[22:01:11.760] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:11.760] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:26:0) after 1m0s
[22:02:11.820] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:26:0)
[22:02:11.820] Timer tick, asking Buildbucket for the build status
[22:02:11.926] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:11.926] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:27:0) after 1m0s
[22:03:11.969] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:27:0)
[22:03:11.969] Timer tick, asking Buildbucket for the build status
[22:03:12.210] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:12.210] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:28:0) after 1m0s
[22:04:12.256] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:28:0)
[22:04:12.256] Timer tick, asking Buildbucket for the build status
[22:04:12.507] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:12.507] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:29:0) after 1m0s
[22:05:12.619] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:29:0)
[22:05:12.619] Timer tick, asking Buildbucket for the build status
[22:05:12.833] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:12.833] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:30:0) after 1m0s
[22:06:12.855] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:30:0)
[22:06:12.855] Timer tick, asking Buildbucket for the build status
[22:06:13.133] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:13.133] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:31:0) after 1m0s
[22:07:13.160] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:31:0)
[22:07:13.160] Timer tick, asking Buildbucket for the build status
[22:07:13.303] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:13.303] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:32:0) after 1m0s
[22:08:13.330] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:32:0)
[22:08:13.330] Timer tick, asking Buildbucket for the build status
[22:08:13.590] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:13.590] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:33:0) after 1m0s
[22:09:13.645] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:33:0)
[22:09:13.645] Timer tick, asking Buildbucket for the build status
[22:09:13.743] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:13.743] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:34:0) after 1m0s
[22:10:13.794] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:34:0)
[22:10:13.794] Timer tick, asking Buildbucket for the build status
[22:10:13.875] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:13.875] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:35:0) after 1m0s
[22:11:13.903] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:35:0)
[22:11:13.903] Timer tick, asking Buildbucket for the build status
[22:11:14.001] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:14.001] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:36:0) after 1m0s
[22:12:14.036] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:36:0)
[22:12:14.036] Timer tick, asking Buildbucket for the build status
[22:12:14.155] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:14.155] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:37:0) after 1m0s
[22:13:14.133] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:37:0)
[22:13:14.133] Timer tick, asking Buildbucket for the build status
[22:13:14.413] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:14.413] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:38:0) after 1m0s
[22:14:14.441] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:38:0)
[22:14:14.441] Timer tick, asking Buildbucket for the build status
[22:14:14.518] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:14.518] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:39:0) after 1m0s
[22:15:14.637] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:39:0)
[22:15:14.637] Timer tick, asking Buildbucket for the build status
[22:15:14.712] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:14.712] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:40:0) after 1m0s
[22:16:14.748] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:40:0)
[22:16:14.748] Timer tick, asking Buildbucket for the build status
[22:16:14.821] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:14.822] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:41:0) after 1m0s
[22:17:14.946] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:41:0)
[22:17:14.946] Timer tick, asking Buildbucket for the build status
[22:17:15.054] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:15.054] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:42:0) after 1m0s
[22:18:15.145] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:42:0)
[22:18:15.145] Timer tick, asking Buildbucket for the build status
[22:18:15.304] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:15.304] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:43:0) after 1m0s
[22:19:15.382] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:43:0)
[22:19:15.382] Timer tick, asking Buildbucket for the build status
[22:19:15.634] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:15.634] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:44:0) after 1m0s
[22:20:15.718] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:44:0)
[22:20:15.718] Timer tick, asking Buildbucket for the build status
[22:20:15.812] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:15.812] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:45:0) after 1m0s
[22:21:15.878] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:45:0)
[22:21:15.878] Timer tick, asking Buildbucket for the build status
[22:21:16.207] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:16.207] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:46:0) after 1m0s
[22:22:16.227] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:46:0)
[22:22:16.227] Timer tick, asking Buildbucket for the build status
[22:22:16.594] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:16.594] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:47:0) after 1m0s
[22:23:16.672] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:47:0)
[22:23:16.672] Timer tick, asking Buildbucket for the build status
[22:23:16.893] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:16.893] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:48:0) after 1m0s
[22:24:16.939] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:48:0)
[22:24:16.939] Timer tick, asking Buildbucket for the build status
[22:24:17.082] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:17.082] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:49:0) after 1m0s
[22:25:17.106] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:49:0)
[22:25:17.106] Timer tick, asking Buildbucket for the build status
[22:25:17.219] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:17.219] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:50:0) after 1m0s
[22:26:17.326] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:50:0)
[22:26:17.326] Timer tick, asking Buildbucket for the build status
[22:26:17.411] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:17.411] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:51:0) after 1m0s
[22:27:17.454] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:51:0)
[22:27:17.454] Timer tick, asking Buildbucket for the build status
[22:27:18.102] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:18.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:52:0) after 1m0s
[22:28:18.154] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:52:0)
[22:28:18.154] Timer tick, asking Buildbucket for the build status
[22:28:18.233] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:18.233] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:53:0) after 1m0s
[22:29:18.317] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:53:0)
[22:29:18.317] Timer tick, asking Buildbucket for the build status
[22:29:19.255] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:19.255] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:54:0) after 1m0s
[22:30:19.354] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:54:0)
[22:30:19.354] Timer tick, asking Buildbucket for the build status
[22:30:19.422] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:19.422] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:55:0) after 1m0s
[22:31:19.473] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:55:0)
[22:31:19.473] Timer tick, asking Buildbucket for the build status
[22:31:19.576] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:19.576] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:56:0) after 1m0s
[22:32:19.662] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:56:0)
[22:32:19.662] Timer tick, asking Buildbucket for the build status
[22:32:20.022] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:20.022] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:57:0) after 1m0s
[22:33:20.156] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:57:0)
[22:33:20.156] Timer tick, asking Buildbucket for the build status
[22:33:20.260] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:20.261] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:58:0) after 1m0s
[22:34:20.307] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:58:0)
[22:34:20.307] Timer tick, asking Buildbucket for the build status
[22:34:20.422] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:20.422] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:59:0) after 1m0s
[22:35:20.473] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:59:0)
[22:35:20.473] Timer tick, asking Buildbucket for the build status
[22:35:20.610] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:20.610] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:60:0) after 1m0s
[22:36:20.713] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:60:0)
[22:36:20.713] Timer tick, asking Buildbucket for the build status
[22:36:20.785] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:20.785] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:61:0) after 1m0s
[22:37:20.831] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:61:0)
[22:37:20.831] Timer tick, asking Buildbucket for the build status
[22:37:20.928] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:20.928] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:62:0) after 1m0s
[22:38:20.932] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:62:0)
[22:38:20.932] Timer tick, asking Buildbucket for the build status
[22:38:21.064] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:21.064] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:63:0) after 1m0s
[22:39:21.153] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:63:0)
[22:39:21.153] Timer tick, asking Buildbucket for the build status
[22:39:21.293] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:21.293] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:64:0) after 1m0s
[22:40:21.362] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:64:0)
[22:40:21.362] Timer tick, asking Buildbucket for the build status
[22:40:21.526] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:21.526] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:65:0) after 1m0s
[22:41:21.836] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:65:0)
[22:41:21.836] Timer tick, asking Buildbucket for the build status
[22:41:21.926] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:21.926] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:66:0) after 1m0s
[22:42:22.031] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:66:0)
[22:42:22.031] Timer tick, asking Buildbucket for the build status
[22:42:22.152] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:22.152] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:67:0) after 1m0s
[22:43:22.207] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:67:0)
[22:43:22.207] Timer tick, asking Buildbucket for the build status
[22:43:22.404] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:22.404] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:68:0) after 1m0s
[22:44:22.455] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:68:0)
[22:44:22.455] Timer tick, asking Buildbucket for the build status
[22:44:22.525] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:22.525] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:69:0) after 1m0s
[22:45:22.531] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:69:0)
[22:45:22.531] Timer tick, asking Buildbucket for the build status
[22:45:22.686] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:22.686] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:70:0) after 1m0s
[22:46:22.712] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:70:0)
[22:46:22.712] Timer tick, asking Buildbucket for the build status
[22:46:22.812] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:22.812] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:71:0) after 1m0s
[22:47:22.834] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:71:0)
[22:47:22.834] Timer tick, asking Buildbucket for the build status
[22:47:22.953] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:22.953] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:72:0) after 1m0s
[22:48:23.001] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:72:0)
[22:48:23.001] Timer tick, asking Buildbucket for the build status
[22:48:23.105] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:23.105] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:73:0) after 1m0s
[22:49:23.152] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:73:0)
[22:49:23.152] Timer tick, asking Buildbucket for the build status
[22:49:23.233] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:23.233] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:74:0) after 1m0s
[22:50:23.293] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:74:0)
[22:50:23.293] Timer tick, asking Buildbucket for the build status
[22:50:23.384] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:23.384] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:75:0) after 1m0s
[22:51:23.420] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:75:0)
[22:51:23.420] Timer tick, asking Buildbucket for the build status
[22:51:23.489] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:23.490] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:76:0) after 1m0s
[22:52:23.606] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:76:0)
[22:52:23.606] Timer tick, asking Buildbucket for the build status
[22:52:23.703] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:23.703] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:77:0) after 1m0s
[22:53:23.777] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:77:0)
[22:53:23.777] Timer tick, asking Buildbucket for the build status
[22:53:24.590] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:24.590] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:78:0) after 1m0s
[22:54:24.630] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:78:0)
[22:54:24.630] Timer tick, asking Buildbucket for the build status
[22:54:24.708] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:24.708] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:79:0) after 1m0s
[22:55:24.728] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:79:0)
[22:55:24.728] Timer tick, asking Buildbucket for the build status
[22:55:24.846] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:24.846] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:80:0) after 1m0s
[22:56:24.896] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:80:0)
[22:56:24.896] Timer tick, asking Buildbucket for the build status
[22:56:24.972] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:24.973] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:81:0) after 1m0s
[22:57:25.043] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:81:0)
[22:57:25.043] Timer tick, asking Buildbucket for the build status
[22:57:25.184] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:25.184] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:82:0) after 1m0s
[22:58:25.196] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:82:0)
[22:58:25.196] Timer tick, asking Buildbucket for the build status
[22:58:25.273] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:25.273] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:83:0) after 1m0s
[22:59:25.250] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:83:0)
[22:59:25.250] Timer tick, asking Buildbucket for the build status
[22:59:25.537] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:25.537] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:84:0) after 1m0s
[23:00:25.626] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:84:0)
[23:00:25.626] Timer tick, asking Buildbucket for the build status
[23:00:25.755] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:25.755] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:85:0) after 1m0s
[23:01:25.783] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:85:0)
[23:01:25.783] Timer tick, asking Buildbucket for the build status
[23:01:25.896] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:25.896] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:86:0) after 1m0s
[23:02:25.950] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:86:0)
[23:02:25.950] Timer tick, asking Buildbucket for the build status
[23:02:26.047] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:26.047] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:87:0) after 1m0s
[23:03:26.086] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:87:0)
[23:03:26.086] Timer tick, asking Buildbucket for the build status
[23:03:26.195] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:26.195] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:88:0) after 1m0s
[23:04:26.303] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:88:0)
[23:04:26.303] Timer tick, asking Buildbucket for the build status
[23:04:26.684] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:26.684] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:89:0) after 1m0s
[23:05:26.763] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:89:0)
[23:05:26.763] Timer tick, asking Buildbucket for the build status
[23:05:26.913] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:26.913] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:90:0) after 1m0s
[23:06:26.943] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:90:0)
[23:06:26.943] Timer tick, asking Buildbucket for the build status
[23:06:27.043] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:27.043] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:91:0) after 1m0s
[23:07:27.028] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:91:0)
[23:07:27.028] Timer tick, asking Buildbucket for the build status
[23:07:27.132] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:27.133] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:92:0) after 1m0s
[23:08:27.147] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:92:0)
[23:08:27.147] Timer tick, asking Buildbucket for the build status
[23:08:27.392] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:27.392] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:93:0) after 1m0s
[23:09:27.467] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:93:0)
[23:09:27.467] Timer tick, asking Buildbucket for the build status
[23:09:27.552] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:27.552] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:94:0) after 1m0s
[23:10:27.624] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:94:0)
[23:10:27.624] Timer tick, asking Buildbucket for the build status
[23:10:27.698] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:27.698] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:95:0) after 1m0s
[23:11:27.702] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:95:0)
[23:11:27.702] Timer tick, asking Buildbucket for the build status
[23:11:27.891] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:27.891] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:96:0) after 1m0s
[23:12:27.996] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:96:0)
[23:12:27.996] Timer tick, asking Buildbucket for the build status
[23:12:28.127] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:28.127] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:97:0) after 1m0s
[23:13:28.223] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:97:0)
[23:13:28.223] Timer tick, asking Buildbucket for the build status
[23:13:28.304] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:28.305] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:98:0) after 1m0s
[23:14:28.309] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:98:0)
[23:14:28.309] Timer tick, asking Buildbucket for the build status
[23:14:28.422] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:28.422] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:99:0) after 1m0s
[23:15:28.640] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:99:0)
[23:15:28.640] Timer tick, asking Buildbucket for the build status
[23:15:28.820] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:28.820] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:100:0) after 1m0s
[23:16:28.910] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:100:0)
[23:16:28.910] Timer tick, asking Buildbucket for the build status
[23:16:28.985] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:28.985] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:101:0) after 1m0s
[23:17:29.009] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:101:0)
[23:17:29.009] Timer tick, asking Buildbucket for the build status
[23:17:29.507] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:29.507] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:102:0) after 1m0s
[23:18:29.640] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:102:0)
[23:18:29.640] Timer tick, asking Buildbucket for the build status
[23:18:29.740] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:29.740] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:103:0) after 1m0s
[23:19:29.856] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:103:0)
[23:19:29.856] Timer tick, asking Buildbucket for the build status
[23:19:29.917] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:29.918] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:104:0) after 1m0s
[23:20:29.986] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:104:0)
[23:20:29.986] Timer tick, asking Buildbucket for the build status
[23:20:30.117] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:30.117] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:105:0) after 1m0s
[23:21:30.171] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:105:0)
[23:21:30.171] Timer tick, asking Buildbucket for the build status
[23:21:30.376] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:30.376] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:106:0) after 1m0s
[23:22:30.531] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:106:0)
[23:22:30.531] Timer tick, asking Buildbucket for the build status
[23:22:31.021] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:31.022] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:107:0) after 1m0s
[23:23:31.138] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:107:0)
[23:23:31.138] Timer tick, asking Buildbucket for the build status
[23:23:31.488] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:31.488] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:108:0) after 1m0s
[23:24:31.850] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:108:0)
[23:24:31.850] Timer tick, asking Buildbucket for the build status
[23:24:32.183] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:32.183] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:109:0) after 1m0s
[23:25:32.336] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:109:0)
[23:25:32.336] Timer tick, asking Buildbucket for the build status
[23:25:32.658] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:32.658] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:110:0) after 1m0s
[23:26:32.726] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:110:0)
[23:26:32.726] Timer tick, asking Buildbucket for the build status
[23:26:32.928] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:32.928] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:111:0) after 1m0s
[23:27:32.967] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:111:0)
[23:27:32.967] Timer tick, asking Buildbucket for the build status
[23:27:33.053] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:33.053] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:112:0) after 1m0s
[23:28:33.076] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:112:0)
[23:28:33.076] Timer tick, asking Buildbucket for the build status
[23:28:33.208] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:33.208] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:113:0) after 1m0s
[23:29:33.363] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:113:0)
[23:29:33.363] Timer tick, asking Buildbucket for the build status
[23:29:33.926] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:33.926] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:114:0) after 1m0s
[23:30:34.089] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:114:0)
[23:30:34.089] Timer tick, asking Buildbucket for the build status
[23:30:34.161] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:34.161] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:115:0) after 1m0s
[23:31:34.210] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:115:0)
[23:31:34.210] Timer tick, asking Buildbucket for the build status
[23:31:34.301] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:34.301] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:116:0) after 1m0s
[23:32:34.353] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:116:0)
[23:32:34.353] Timer tick, asking Buildbucket for the build status
[23:32:34.450] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:34.455] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:117:0) after 1m0s
[23:33:34.529] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:117:0)
[23:33:34.529] Timer tick, asking Buildbucket for the build status
[23:33:34.851] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:34.851] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:118:0) after 1m0s
[23:34:34.923] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:118:0)
[23:34:34.923] Timer tick, asking Buildbucket for the build status
[23:34:35.041] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:35.041] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:119:0) after 1m0s
[23:35:35.100] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:119:0)
[23:35:35.100] Timer tick, asking Buildbucket for the build status
[23:35:35.924] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:35.924] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:120:0) after 1m0s
[23:36:35.984] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:120:0)
[23:36:35.984] Timer tick, asking Buildbucket for the build status
[23:36:36.053] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:36.053] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:121:0) after 1m0s
[23:37:36.127] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:121:0)
[23:37:36.127] Timer tick, asking Buildbucket for the build status
[23:37:36.225] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:36.225] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:122:0) after 1m0s
[23:38:36.234] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:122:0)
[23:38:36.234] Timer tick, asking Buildbucket for the build status
[23:38:36.497] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:36.497] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:123:0) after 1m0s
[23:39:36.547] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:123:0)
[23:39:36.548] Timer tick, asking Buildbucket for the build status
[23:39:36.656] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:36.656] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:124:0) after 1m0s
[23:40:36.668] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:124:0)
[23:40:36.668] Timer tick, asking Buildbucket for the build status
[23:40:36.761] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:36.761] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:125:0) after 1m0s
[23:41:36.830] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:125:0)
[23:41:36.830] Timer tick, asking Buildbucket for the build status
[23:41:36.889] Build 8866519578935599552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:36.889] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:126:0) after 1m0s
[23:42:30.876] Received PubSub notification, asking Buildbucket for the build status
[23:42:31.021] Build 8866519578935599552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.901] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:126:0)
[23:42:36.901] Timer tick, asking Buildbucket for the build status
[23:42:36.981] Build 8866519578935599552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9031949641137306112:128:0) after 1m0s
[23:43:07.930] Received PubSub notification, asking Buildbucket for the build status
[23:43:07.995] Build 8866519578935599552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:43:07.995] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1602632586504820",
"created_by": "project:infra",
"created_ts": "1602625023863231",
"id": "8866519578935599552",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"13755\"}, \"id\": \"cron:v1:13755\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_v2\", \"builder_4d1db109d30b9f0068cf0464722c703e05f788cd82e2d29876a9483818e7c333_v2\", \"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"builder_ca1dd8081634cfdf63cf6543806b7419b3e097f184142241b02f8854d1768d0b_v2\", \"builder_d09004ff56ad3d259bfb1375112387cace64bb5a4afee3da7ed4cf5892d6b500_v2\", \"builder_dbab9abe7188467828c33829af37b0f6a86a533613509fd8bbd879beea667f22_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-1-0l9h\"], \"image\": [\"chrome-xenial-20101100-265b45aa9e2\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602632549397744",
"status": "COMPLETED",
"status_changed_ts": "1602632587332085",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9031949641137306112",
"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/8866519578935599552/+/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:4f3cb6bab8050a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1602632587339708",
"url": "https://ci.chromium.org/b/8866519578935599552",
"utcnow_ts": "1602632587987936"
}
}
[23:43:07.995] Invocation finished in 2h6m5.614244737s with status SUCCEEDED