[15:37:02.675] New invocation is queued and will start shortly
[15:37:03.841] Starting the invocation (attempt 1)
[15:37:03.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:37:03.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:37:03.917] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9065674361588780128",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"4821\"},\"id\":\"cron:v1:4821\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9065674361588780128",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[15:37:04.372] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1570462624058722",
"id": "8900244299473736720",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4821\"}, \"id\": \"cron:v1:4821\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570462624213216",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9065674361588780128",
"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/8900244299473736720/+/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": "1570462624213333",
"url": "https://ci.chromium.org/b/8900244299473736720",
"utcnow_ts": "1570462624358483"
}
}
[15:37:04.372] Task URL: https://ci.chromium.org/b/8900244299473736720
[15:37:04.372] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:2:0) after 1m0s
[15:38:04.459] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:2:0)
[15:38:04.459] Timer tick, asking Buildbucket for the build status
[15:38:04.653] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:04.653] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:3:0) after 1m0s
[15:39:04.732] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:3:0)
[15:39:04.732] Timer tick, asking Buildbucket for the build status
[15:39:04.852] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:04.852] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:4:0) after 1m0s
[15:40:04.836] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:4:0)
[15:40:04.836] Timer tick, asking Buildbucket for the build status
[15:40:08.754] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:08.754] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:5:0) after 1m0s
[15:41:08.922] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:5:0)
[15:41:08.922] Timer tick, asking Buildbucket for the build status
[15:41:09.117] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:09.117] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:6:0) after 1m0s
[15:42:09.145] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:6:0)
[15:42:09.145] Timer tick, asking Buildbucket for the build status
[15:42:09.237] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:09.237] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:7:0) after 1m0s
[15:43:09.272] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:7:0)
[15:43:09.272] Timer tick, asking Buildbucket for the build status
[15:43:09.579] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:09.579] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:8:0) after 1m0s
[15:44:09.702] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:8:0)
[15:44:09.702] Timer tick, asking Buildbucket for the build status
[15:44:12.836] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:12.836] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:9:0) after 1m0s
[15:45:12.973] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:9:0)
[15:45:12.973] Timer tick, asking Buildbucket for the build status
[15:45:13.297] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:13.297] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:10:0) after 1m0s
[15:46:13.542] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:10:0)
[15:46:13.542] Timer tick, asking Buildbucket for the build status
[15:46:13.762] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:13.762] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:11:0) after 1m0s
[15:47:13.801] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:11:0)
[15:47:13.801] Timer tick, asking Buildbucket for the build status
[15:47:13.887] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:13.887] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:12:0) after 1m0s
[15:48:13.977] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:12:0)
[15:48:13.977] Timer tick, asking Buildbucket for the build status
[15:48:14.096] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:14.096] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:13:0) after 1m0s
[15:49:14.282] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:13:0)
[15:49:14.282] Timer tick, asking Buildbucket for the build status
[15:49:14.612] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:14.612] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:14:0) after 1m0s
[15:50:14.815] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:14:0)
[15:50:14.815] Timer tick, asking Buildbucket for the build status
[15:50:15.204] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:15.204] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:15:0) after 1m0s
[15:51:15.347] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:15:0)
[15:51:15.347] Timer tick, asking Buildbucket for the build status
[15:51:15.544] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:51:15.544] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:16:0) after 1m0s
[15:52:15.602] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:16:0)
[15:52:15.602] Timer tick, asking Buildbucket for the build status
[15:52:16.583] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:52:16.583] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:17:0) after 1m0s
[15:53:16.743] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:17:0)
[15:53:16.743] Timer tick, asking Buildbucket for the build status
[15:53:17.236] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:53:17.236] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:18:0) after 1m0s
[15:54:17.574] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:18:0)
[15:54:17.574] Timer tick, asking Buildbucket for the build status
[15:54:20.118] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:54:20.118] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:19:0) after 1m0s
[15:55:20.205] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:19:0)
[15:55:20.205] Timer tick, asking Buildbucket for the build status
[15:55:21.560] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:21.560] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:20:0) after 1m0s
[15:56:21.678] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:20:0)
[15:56:21.678] Timer tick, asking Buildbucket for the build status
[15:56:22.405] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:22.405] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:21:0) after 1m0s
[15:57:22.532] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:21:0)
[15:57:22.532] Timer tick, asking Buildbucket for the build status
[15:57:22.670] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:22.670] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:22:0) after 1m0s
[15:58:22.739] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:22:0)
[15:58:22.739] Timer tick, asking Buildbucket for the build status
[15:58:23.026] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:58:23.026] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:23:0) after 1m0s
[15:59:23.246] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:23:0)
[15:59:23.246] Timer tick, asking Buildbucket for the build status
[15:59:23.621] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:23.621] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:24:0) after 1m0s
[16:00:23.762] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:24:0)
[16:00:23.762] Timer tick, asking Buildbucket for the build status
[16:00:24.309] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:24.309] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:25:0) after 1m0s
[16:01:24.368] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:25:0)
[16:01:24.368] Timer tick, asking Buildbucket for the build status
[16:01:24.482] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:24.482] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:26:0) after 1m0s
[16:02:24.510] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:26:0)
[16:02:24.510] Timer tick, asking Buildbucket for the build status
[16:02:26.366] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:26.366] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:27:0) after 1m0s
[16:03:26.497] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:27:0)
[16:03:26.497] Timer tick, asking Buildbucket for the build status
[16:03:26.746] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:03:26.746] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:28:0) after 1m0s
[16:04:26.832] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:28:0)
[16:04:26.832] Timer tick, asking Buildbucket for the build status
[16:04:27.106] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:04:27.106] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:29:0) after 1m0s
[16:05:27.303] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:29:0)
[16:05:27.303] Timer tick, asking Buildbucket for the build status
[16:05:27.759] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:27.760] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:30:0) after 1m0s
[16:06:27.796] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:30:0)
[16:06:27.796] Timer tick, asking Buildbucket for the build status
[16:06:27.999] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:27.999] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:31:0) after 1m0s
[16:07:28.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:31:0)
[16:07:28.302] Timer tick, asking Buildbucket for the build status
[16:07:28.490] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:28.490] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:32:0) after 1m0s
[16:08:28.615] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:32:0)
[16:08:28.615] Timer tick, asking Buildbucket for the build status
[16:08:28.723] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:28.723] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:33:0) after 1m0s
[16:09:28.793] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:33:0)
[16:09:28.793] Timer tick, asking Buildbucket for the build status
[16:09:29.064] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:29.064] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:34:0) after 1m0s
[16:10:29.154] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:34:0)
[16:10:29.154] Timer tick, asking Buildbucket for the build status
[16:10:33.234] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:33.234] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:35:0) after 1m0s
[16:11:33.335] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:35:0)
[16:11:33.335] Timer tick, asking Buildbucket for the build status
[16:11:33.979] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:33.979] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:36:0) after 1m0s
[16:12:34.056] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:36:0)
[16:12:34.056] Timer tick, asking Buildbucket for the build status
[16:12:34.307] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:34.307] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:37:0) after 1m0s
[16:13:34.409] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:37:0)
[16:13:34.409] Timer tick, asking Buildbucket for the build status
[16:13:34.663] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:34.663] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:38:0) after 1m0s
[16:14:34.785] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:38:0)
[16:14:34.785] Timer tick, asking Buildbucket for the build status
[16:14:35.102] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:35.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:39:0) after 1m0s
[16:15:35.133] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:39:0)
[16:15:35.133] Timer tick, asking Buildbucket for the build status
[16:15:35.340] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:35.340] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:40:0) after 1m0s
[16:16:35.480] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:40:0)
[16:16:35.480] Timer tick, asking Buildbucket for the build status
[16:16:35.605] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:35.605] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:41:0) after 1m0s
[16:17:35.652] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:41:0)
[16:17:35.652] Timer tick, asking Buildbucket for the build status
[16:17:35.893] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:35.893] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:42:0) after 1m0s
[16:18:36.112] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:42:0)
[16:18:36.112] Timer tick, asking Buildbucket for the build status
[16:18:36.311] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:36.311] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:43:0) after 1m0s
[16:19:36.427] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:43:0)
[16:19:36.427] Timer tick, asking Buildbucket for the build status
[16:19:37.378] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:37.378] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:44:0) after 1m0s
[16:20:37.524] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:44:0)
[16:20:37.524] Timer tick, asking Buildbucket for the build status
[16:20:37.835] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:37.835] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:45:0) after 1m0s
[16:21:37.878] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:45:0)
[16:21:37.878] Timer tick, asking Buildbucket for the build status
[16:21:37.990] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:37.990] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:46:0) after 1m0s
[16:22:38.006] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:46:0)
[16:22:38.006] Timer tick, asking Buildbucket for the build status
[16:22:38.363] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:38.363] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:47:0) after 1m0s
[16:23:38.437] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:47:0)
[16:23:38.437] Timer tick, asking Buildbucket for the build status
[16:23:38.759] Build 8900244299473736720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:38.760] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:48:0) after 1m0s
[16:24:20.387] Received PubSub notification, asking Buildbucket for the build status
[16:24:20.493] Build 8900244299473736720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:39.055] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:48:0)
[16:24:39.055] Timer tick, asking Buildbucket for the build status
[16:24:39.178] Build 8900244299473736720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:39.178] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9065674361588780128:50:0) after 1m0s
[16:25:19.557] Received PubSub notification, asking Buildbucket for the build status
[16:25:19.664] Build 8900244299473736720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:25:19.664] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1570465517507473",
"created_by": "project:infra",
"created_ts": "1570462624058722",
"id": "8900244299473736720",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4821\"}, \"id\": \"cron:v1:4821\"}]}}}",
"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\": \"4821\"}, \"id\": \"cron:v1:4821\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-1-fydg\", \"buildername\": \"publish_tarball_dispatcher\", \"path_config\": \"generic\", \"recipe\": \"publish_tarball\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"git\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-1-fydg\"], \"image\": [\"chrome-xenial-19090600-ed917d8233f\"], \"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\"], \"python3\": [\"3.5.2\"], \"server_version\": [\"4466-755d6a2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570465459463118",
"status": "COMPLETED",
"status_changed_ts": "1570465518605106",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9065674361588780128",
"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/8900244299473736720/+/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:47bfafb0483bf310",
"user_agent:luci-scheduler"
],
"updated_ts": "1570465518739340",
"url": "https://ci.chromium.org/b/8900244299473736720",
"utcnow_ts": "1570465519652879"
}
}
[16:25:19.664] Invocation finished in 48m17.006480339s with status SUCCEEDED