[00:37:02.309] New invocation is queued and will start shortly
[00:37:04.325] Starting the invocation (attempt 1)
[00:37:04.369] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:37:04.369] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:37:04.369] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9047792785728419088",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"9558\"},\"id\":\"cron:v1:9558\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9047792785728419088",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[00:37:06.279] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1587515824530296",
"id": "8882362722735636640",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"9558\"}, \"id\": \"cron:v1:9558\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587515824978084",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9047792785728419088",
"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/8882362722735636640/+/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": "1587515824978205",
"url": "https://ci.chromium.org/b/8882362722735636640",
"utcnow_ts": "1587515826259716"
}
}
[00:37:06.279] Task URL: https://ci.chromium.org/b/8882362722735636640
[00:37:06.279] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:2:0) after 1m0s
[00:38:06.381] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:2:0)
[00:38:06.381] Timer tick, asking Buildbucket for the build status
[00:38:06.537] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:06.537] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:3:0) after 1m0s
[00:39:06.634] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:3:0)
[00:39:06.635] Timer tick, asking Buildbucket for the build status
[00:39:06.831] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:06.831] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:4:0) after 1m0s
[00:40:06.903] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:4:0)
[00:40:06.903] Timer tick, asking Buildbucket for the build status
[00:40:07.163] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:07.163] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:5:0) after 1m0s
[00:41:07.259] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:5:0)
[00:41:07.259] Timer tick, asking Buildbucket for the build status
[00:41:07.534] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:07.534] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:6:0) after 1m0s
[00:42:07.634] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:6:0)
[00:42:07.634] Timer tick, asking Buildbucket for the build status
[00:42:07.991] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:07.991] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:7:0) after 1m0s
[00:43:08.081] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:7:0)
[00:43:08.081] Timer tick, asking Buildbucket for the build status
[00:43:08.255] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:08.255] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:8:0) after 1m0s
[00:44:08.293] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:8:0)
[00:44:08.293] Timer tick, asking Buildbucket for the build status
[00:44:08.541] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:08.541] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:9:0) after 1m0s
[00:45:08.625] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:9:0)
[00:45:08.625] Timer tick, asking Buildbucket for the build status
[00:45:08.986] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:08.986] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:10:0) after 1m0s
[00:46:09.058] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:10:0)
[00:46:09.058] Timer tick, asking Buildbucket for the build status
[00:46:09.437] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:09.437] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:11:0) after 1m0s
[00:47:09.473] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:11:0)
[00:47:09.473] Timer tick, asking Buildbucket for the build status
[00:47:09.724] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:09.724] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:12:0) after 1m0s
[00:48:09.824] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:12:0)
[00:48:09.824] Timer tick, asking Buildbucket for the build status
[00:48:09.944] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:09.944] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:13:0) after 1m0s
[00:49:10.000] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:13:0)
[00:49:10.000] Timer tick, asking Buildbucket for the build status
[00:49:10.454] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:10.454] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:14:0) after 1m0s
[00:50:10.555] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:14:0)
[00:50:10.555] Timer tick, asking Buildbucket for the build status
[00:50:10.713] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:10.713] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:15:0) after 1m0s
[00:51:10.767] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:15:0)
[00:51:10.767] Timer tick, asking Buildbucket for the build status
[00:51:10.968] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:10.968] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:16:0) after 1m0s
[00:52:11.379] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:16:0)
[00:52:11.379] Timer tick, asking Buildbucket for the build status
[00:52:11.511] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:11.511] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:17:0) after 1m0s
[00:53:11.661] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:17:0)
[00:53:11.661] Timer tick, asking Buildbucket for the build status
[00:53:11.755] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:11.755] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:18:0) after 1m0s
[00:54:11.979] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:18:0)
[00:54:11.979] Timer tick, asking Buildbucket for the build status
[00:54:13.680] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:13.680] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:19:0) after 1m0s
[00:55:13.745] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:19:0)
[00:55:13.745] Timer tick, asking Buildbucket for the build status
[00:55:13.862] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:13.862] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:20:0) after 1m0s
[00:56:13.923] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:20:0)
[00:56:13.923] Timer tick, asking Buildbucket for the build status
[00:56:14.094] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:14.094] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:21:0) after 1m0s
[00:57:14.366] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:21:0)
[00:57:14.366] Timer tick, asking Buildbucket for the build status
[00:57:14.908] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:14.908] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:22:0) after 1m0s
[00:58:15.266] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:22:0)
[00:58:15.266] Timer tick, asking Buildbucket for the build status
[00:58:15.415] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:15.415] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:23:0) after 1m0s
[00:59:15.813] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:23:0)
[00:59:15.813] Timer tick, asking Buildbucket for the build status
[00:59:16.860] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:16.860] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:24:0) after 1m0s
[01:00:17.096] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:24:0)
[01:00:17.096] Timer tick, asking Buildbucket for the build status
[01:00:18.782] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:18.782] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:25:0) after 1m0s
[01:01:19.063] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:25:0)
[01:01:19.063] Timer tick, asking Buildbucket for the build status
[01:01:19.285] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:19.285] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:26:0) after 1m0s
[01:02:19.501] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:26:0)
[01:02:19.501] Timer tick, asking Buildbucket for the build status
[01:02:19.624] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:19.624] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:27:0) after 1m0s
[01:03:19.886] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:27:0)
[01:03:19.886] Timer tick, asking Buildbucket for the build status
[01:03:20.201] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:20.201] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:28:0) after 1m0s
[01:04:20.267] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:28:0)
[01:04:20.267] Timer tick, asking Buildbucket for the build status
[01:04:20.981] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:20.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:29:0) after 1m0s
[01:05:21.211] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:29:0)
[01:05:21.211] Timer tick, asking Buildbucket for the build status
[01:05:21.335] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:21.335] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:30:0) after 1m0s
[01:06:21.409] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:30:0)
[01:06:21.409] Timer tick, asking Buildbucket for the build status
[01:06:21.599] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:21.599] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:31:0) after 1m0s
[01:07:21.723] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:31:0)
[01:07:21.723] Timer tick, asking Buildbucket for the build status
[01:07:21.857] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:21.857] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:32:0) after 1m0s
[01:08:22.084] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:32:0)
[01:08:22.084] Timer tick, asking Buildbucket for the build status
[01:08:22.460] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:22.460] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:33:0) after 1m0s
[01:09:22.525] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:33:0)
[01:09:22.525] Timer tick, asking Buildbucket for the build status
[01:09:22.904] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:22.904] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:34:0) after 1m0s
[01:10:23.041] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:34:0)
[01:10:23.042] Timer tick, asking Buildbucket for the build status
[01:10:23.479] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:23.479] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:35:0) after 1m0s
[01:11:23.533] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:35:0)
[01:11:23.533] Timer tick, asking Buildbucket for the build status
[01:11:23.819] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:23.819] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:36:0) after 1m0s
[01:12:23.973] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:36:0)
[01:12:23.973] Timer tick, asking Buildbucket for the build status
[01:12:24.175] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:24.175] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:37:0) after 1m0s
[01:13:24.225] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:37:0)
[01:13:24.225] Timer tick, asking Buildbucket for the build status
[01:13:24.337] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:24.337] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:38:0) after 1m0s
[01:14:24.383] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:38:0)
[01:14:24.383] Timer tick, asking Buildbucket for the build status
[01:14:24.605] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:24.605] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:39:0) after 1m0s
[01:15:24.665] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:39:0)
[01:15:24.665] Timer tick, asking Buildbucket for the build status
[01:15:24.865] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:24.865] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:40:0) after 1m0s
[01:16:25.036] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:40:0)
[01:16:25.036] Timer tick, asking Buildbucket for the build status
[01:16:25.186] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:25.186] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:41:0) after 1m0s
[01:17:25.472] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:41:0)
[01:17:25.472] Timer tick, asking Buildbucket for the build status
[01:17:25.600] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:25.600] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:42:0) after 1m0s
[01:18:25.675] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:42:0)
[01:18:25.675] Timer tick, asking Buildbucket for the build status
[01:18:25.892] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:25.892] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:43:0) after 1m0s
[01:19:25.921] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:43:0)
[01:19:25.921] Timer tick, asking Buildbucket for the build status
[01:19:26.123] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:26.123] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:44:0) after 1m0s
[01:20:26.155] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:44:0)
[01:20:26.155] Timer tick, asking Buildbucket for the build status
[01:20:26.297] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:26.297] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:45:0) after 1m0s
[01:21:26.387] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:45:0)
[01:21:26.387] Timer tick, asking Buildbucket for the build status
[01:21:26.613] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:26.613] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:46:0) after 1m0s
[01:22:26.706] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:46:0)
[01:22:26.706] Timer tick, asking Buildbucket for the build status
[01:22:26.835] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:26.835] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:47:0) after 1m0s
[01:23:26.860] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:47:0)
[01:23:26.860] Timer tick, asking Buildbucket for the build status
[01:23:27.271] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:27.271] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:48:0) after 1m0s
[01:24:27.294] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:48:0)
[01:24:27.294] Timer tick, asking Buildbucket for the build status
[01:24:27.459] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:27.459] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:49:0) after 1m0s
[01:25:27.563] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:49:0)
[01:25:27.563] Timer tick, asking Buildbucket for the build status
[01:25:27.753] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:27.753] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:50:0) after 1m0s
[01:26:27.868] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:50:0)
[01:26:27.868] Timer tick, asking Buildbucket for the build status
[01:26:28.021] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:28.021] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:51:0) after 1m0s
[01:27:28.154] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:51:0)
[01:27:28.154] Timer tick, asking Buildbucket for the build status
[01:27:28.398] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:28.398] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:52:0) after 1m0s
[01:28:28.471] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:52:0)
[01:28:28.471] Timer tick, asking Buildbucket for the build status
[01:28:28.904] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:28.904] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:53:0) after 1m0s
[01:29:28.927] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:53:0)
[01:29:28.927] Timer tick, asking Buildbucket for the build status
[01:29:29.321] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:29.321] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:54:0) after 1m0s
[01:30:29.372] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:54:0)
[01:30:29.372] Timer tick, asking Buildbucket for the build status
[01:30:30.337] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:30.337] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:55:0) after 1m0s
[01:31:30.403] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:55:0)
[01:31:30.403] Timer tick, asking Buildbucket for the build status
[01:31:31.237] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:31.238] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:56:0) after 1m0s
[01:32:31.245] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:56:0)
[01:32:31.245] Timer tick, asking Buildbucket for the build status
[01:32:31.471] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:31.471] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:57:0) after 1m0s
[01:33:31.650] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:57:0)
[01:33:31.650] Timer tick, asking Buildbucket for the build status
[01:33:31.790] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:31.790] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:58:0) after 1m0s
[01:34:32.025] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:58:0)
[01:34:32.025] Timer tick, asking Buildbucket for the build status
[01:34:32.653] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:32.653] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:59:0) after 1m0s
[01:35:32.714] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:59:0)
[01:35:32.714] Timer tick, asking Buildbucket for the build status
[01:35:32.835] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:32.835] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:60:0) after 1m0s
[01:36:32.915] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:60:0)
[01:36:32.915] Timer tick, asking Buildbucket for the build status
[01:36:33.094] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:33.094] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:61:0) after 1m0s
[01:37:33.168] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:61:0)
[01:37:33.168] Timer tick, asking Buildbucket for the build status
[01:37:33.606] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:33.606] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:62:0) after 1m0s
[01:38:33.671] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:62:0)
[01:38:33.671] Timer tick, asking Buildbucket for the build status
[01:38:33.866] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:33.866] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:63:0) after 1m0s
[01:39:33.971] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:63:0)
[01:39:33.971] Timer tick, asking Buildbucket for the build status
[01:39:34.479] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:34.479] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:64:0) after 1m0s
[01:40:34.592] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:64:0)
[01:40:34.592] Timer tick, asking Buildbucket for the build status
[01:40:34.764] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:34.764] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:65:0) after 1m0s
[01:41:34.798] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:65:0)
[01:41:34.798] Timer tick, asking Buildbucket for the build status
[01:41:34.913] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:34.913] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:66:0) after 1m0s
[01:42:35.019] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:66:0)
[01:42:35.019] Timer tick, asking Buildbucket for the build status
[01:42:35.144] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:35.144] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:67:0) after 1m0s
[01:43:35.182] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:67:0)
[01:43:35.182] Timer tick, asking Buildbucket for the build status
[01:43:35.418] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:35.418] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:68:0) after 1m0s
[01:44:35.519] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:68:0)
[01:44:35.519] Timer tick, asking Buildbucket for the build status
[01:44:36.631] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:36.631] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:69:0) after 1m0s
[01:45:36.759] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:69:0)
[01:45:36.759] Timer tick, asking Buildbucket for the build status
[01:45:37.038] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:37.038] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:70:0) after 1m0s
[01:46:37.069] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:70:0)
[01:46:37.069] Timer tick, asking Buildbucket for the build status
[01:46:37.216] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:37.216] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:71:0) after 1m0s
[01:47:37.272] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:71:0)
[01:47:37.272] Timer tick, asking Buildbucket for the build status
[01:47:37.391] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:37.391] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:72:0) after 1m0s
[01:48:37.566] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:72:0)
[01:48:37.566] Timer tick, asking Buildbucket for the build status
[01:48:37.679] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:37.679] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:73:0) after 1m0s
[01:49:37.735] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:73:0)
[01:49:37.735] Timer tick, asking Buildbucket for the build status
[01:49:41.247] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:41.247] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:74:0) after 1m0s
[01:50:41.320] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:74:0)
[01:50:41.320] Timer tick, asking Buildbucket for the build status
[01:50:41.491] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:41.491] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:75:0) after 1m0s
[01:51:41.527] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:75:0)
[01:51:41.527] Timer tick, asking Buildbucket for the build status
[01:51:41.697] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:41.697] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:76:0) after 1m0s
[01:52:41.769] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:76:0)
[01:52:41.769] Timer tick, asking Buildbucket for the build status
[01:52:42.074] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:42.074] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:77:0) after 1m0s
[01:53:42.132] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:77:0)
[01:53:42.132] Timer tick, asking Buildbucket for the build status
[01:53:42.338] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:42.338] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:78:0) after 1m0s
[01:54:42.435] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:78:0)
[01:54:42.435] Timer tick, asking Buildbucket for the build status
[01:54:42.573] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:42.573] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:79:0) after 1m0s
[01:55:42.686] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:79:0)
[01:55:42.686] Timer tick, asking Buildbucket for the build status
[01:55:42.979] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:42.979] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:80:0) after 1m0s
[01:56:43.149] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:80:0)
[01:56:43.149] Timer tick, asking Buildbucket for the build status
[01:56:43.272] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:43.272] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:81:0) after 1m0s
[01:57:43.331] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:81:0)
[01:57:43.331] Timer tick, asking Buildbucket for the build status
[01:57:43.557] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:43.557] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:82:0) after 1m0s
[01:58:43.644] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:82:0)
[01:58:43.644] Timer tick, asking Buildbucket for the build status
[01:58:43.965] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:43.965] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:83:0) after 1m0s
[01:59:44.049] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:83:0)
[01:59:44.049] Timer tick, asking Buildbucket for the build status
[01:59:45.499] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:45.499] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:84:0) after 1m0s
[02:00:45.566] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:84:0)
[02:00:45.566] Timer tick, asking Buildbucket for the build status
[02:00:45.701] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:45.701] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:85:0) after 1m0s
[02:01:45.768] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:85:0)
[02:01:45.768] Timer tick, asking Buildbucket for the build status
[02:01:45.928] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:45.928] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:86:0) after 1m0s
[02:02:45.957] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:86:0)
[02:02:45.957] Timer tick, asking Buildbucket for the build status
[02:02:47.098] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:47.098] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:87:0) after 1m0s
[02:03:47.138] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:87:0)
[02:03:47.138] Timer tick, asking Buildbucket for the build status
[02:03:47.293] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:47.293] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:88:0) after 1m0s
[02:04:47.318] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:88:0)
[02:04:47.318] Timer tick, asking Buildbucket for the build status
[02:04:48.064] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:48.064] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:89:0) after 1m0s
[02:05:48.121] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:89:0)
[02:05:48.121] Timer tick, asking Buildbucket for the build status
[02:05:48.261] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:48.261] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:90:0) after 1m0s
[02:06:48.283] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:90:0)
[02:06:48.283] Timer tick, asking Buildbucket for the build status
[02:06:48.576] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:48.576] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:91:0) after 1m0s
[02:07:48.583] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:91:0)
[02:07:48.583] Timer tick, asking Buildbucket for the build status
[02:07:48.964] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:48.964] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:92:0) after 1m0s
[02:08:49.012] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:92:0)
[02:08:49.012] Timer tick, asking Buildbucket for the build status
[02:08:49.165] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:49.165] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:93:0) after 1m0s
[02:09:49.208] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:93:0)
[02:09:49.208] Timer tick, asking Buildbucket for the build status
[02:09:49.338] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:49.338] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:94:0) after 1m0s
[02:10:49.325] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:94:0)
[02:10:49.325] Timer tick, asking Buildbucket for the build status
[02:10:49.494] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:49.494] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:95:0) after 1m0s
[02:11:49.476] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:95:0)
[02:11:49.476] Timer tick, asking Buildbucket for the build status
[02:11:49.694] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:49.694] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:96:0) after 1m0s
[02:12:49.730] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:96:0)
[02:12:49.730] Timer tick, asking Buildbucket for the build status
[02:12:50.111] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:50.111] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:97:0) after 1m0s
[02:13:50.156] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:97:0)
[02:13:50.156] Timer tick, asking Buildbucket for the build status
[02:13:50.360] Build 8882362722735636640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:50.360] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:98:0) after 1m0s
[02:14:30.901] Received PubSub notification, asking Buildbucket for the build status
[02:14:30.993] Build 8882362722735636640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:50.390] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:98:0)
[02:14:50.390] Timer tick, asking Buildbucket for the build status
[02:14:50.593] Build 8882362722735636640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:50.593] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9047792785728419088:100:0) after 1m0s
[02:15:04.106] Received PubSub notification, asking Buildbucket for the build status
[02:15:04.204] Build 8882362722735636640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:15:04.204] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1587521703319190",
"created_by": "project:infra",
"created_ts": "1587515824530296",
"id": "8882362722735636640",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"9558\"}, \"id\": \"cron:v1:9558\"}]}}}",
"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\": \"9558\"}, \"id\": \"cron:v1:9558\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-0-zetr\", \"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\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-0-zetr\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"4997-2128d8d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1587521670133757",
"status": "COMPLETED",
"status_changed_ts": "1587521703878266",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9047792785728419088",
"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/8882362722735636640/+/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:4bb822a63f7d0f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1587521703889204",
"url": "https://ci.chromium.org/b/8882362722735636640",
"utcnow_ts": "1587521704188387"
}
}
[02:15:04.204] Invocation finished in 1h38m1.911194002s with status SUCCEEDED