[12:37:02.170] New invocation is queued and will start shortly
[12:37:03.203] Starting the invocation (attempt 1)
[12:37:03.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:37:03.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:37:03.354] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9082899110354185712",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9082899110354185712",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[12:37:04.710] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1554035823426860",
"id": "8917469048373175424",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554035823552207",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9082899110354185712",
"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/8917469048373175424/+/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": "1554035823552304",
"url": "https://ci.chromium.org/b/8917469048373175424",
"utcnow_ts": "1554035824702503"
}
}
[12:37:04.710] Task URL: https://ci.chromium.org/b/8917469048373175424
[12:37:04.711] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:2:0) after 1m0s
[12:38:04.735] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:2:0)
[12:38:04.735] Timer tick, asking Buildbucket for the build status
[12:38:05.073] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:05.073] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:3:0) after 1m0s
[12:39:05.054] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:3:0)
[12:39:05.054] Timer tick, asking Buildbucket for the build status
[12:39:05.188] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:05.188] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:4:0) after 1m0s
[12:40:05.173] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:4:0)
[12:40:05.173] Timer tick, asking Buildbucket for the build status
[12:40:05.270] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:05.270] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:5:0) after 1m0s
[12:41:05.291] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:5:0)
[12:41:05.291] Timer tick, asking Buildbucket for the build status
[12:41:05.416] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:05.416] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:6:0) after 1m0s
[12:42:05.447] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:6:0)
[12:42:05.447] Timer tick, asking Buildbucket for the build status
[12:42:05.573] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:05.574] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:7:0) after 1m0s
[12:43:05.590] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:7:0)
[12:43:05.590] Timer tick, asking Buildbucket for the build status
[12:43:05.764] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:05.764] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:8:0) after 1m0s
[12:44:05.781] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:8:0)
[12:44:05.781] Timer tick, asking Buildbucket for the build status
[12:44:06.021] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:06.021] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:9:0) after 1m0s
[12:45:06.037] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:9:0)
[12:45:06.037] Timer tick, asking Buildbucket for the build status
[12:45:06.365] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:06.365] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:10:0) after 1m0s
[12:46:06.382] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:10:0)
[12:46:06.382] Timer tick, asking Buildbucket for the build status
[12:46:06.462] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:06.462] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:11:0) after 1m0s
[12:47:06.480] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:11:0)
[12:47:06.480] Timer tick, asking Buildbucket for the build status
[12:47:06.638] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:06.638] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:12:0) after 1m0s
[12:48:06.626] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:12:0)
[12:48:06.626] Timer tick, asking Buildbucket for the build status
[12:48:06.726] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:06.726] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:13:0) after 1m0s
[12:49:06.737] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:13:0)
[12:49:06.737] Timer tick, asking Buildbucket for the build status
[12:49:06.802] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:06.802] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:14:0) after 1m0s
[12:50:06.829] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:14:0)
[12:50:06.829] Timer tick, asking Buildbucket for the build status
[12:50:07.053] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:07.053] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:15:0) after 1m0s
[12:51:07.079] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:15:0)
[12:51:07.079] Timer tick, asking Buildbucket for the build status
[12:51:07.157] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:07.157] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:16:0) after 1m0s
[12:52:07.204] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:16:0)
[12:52:07.204] Timer tick, asking Buildbucket for the build status
[12:52:07.387] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:07.387] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:17:0) after 1m0s
[12:53:07.420] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:17:0)
[12:53:07.420] Timer tick, asking Buildbucket for the build status
[12:53:07.533] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:07.533] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:18:0) after 1m0s
[12:54:07.551] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:18:0)
[12:54:07.551] Timer tick, asking Buildbucket for the build status
[12:54:07.604] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:07.604] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:19:0) after 1m0s
[12:55:07.622] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:19:0)
[12:55:07.622] Timer tick, asking Buildbucket for the build status
[12:55:07.818] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:07.818] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:20:0) after 1m0s
[12:56:07.845] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:20:0)
[12:56:07.845] Timer tick, asking Buildbucket for the build status
[12:56:07.916] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:07.916] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:21:0) after 1m0s
[12:57:07.982] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:21:0)
[12:57:07.982] Timer tick, asking Buildbucket for the build status
[12:57:08.159] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:08.159] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:22:0) after 1m0s
[12:58:08.176] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:22:0)
[12:58:08.176] Timer tick, asking Buildbucket for the build status
[12:58:08.457] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:08.457] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:23:0) after 1m0s
[12:59:08.478] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:23:0)
[12:59:08.478] Timer tick, asking Buildbucket for the build status
[12:59:08.552] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:08.552] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:24:0) after 1m0s
[13:00:08.571] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:24:0)
[13:00:08.571] Timer tick, asking Buildbucket for the build status
[13:00:08.709] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:08.709] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:25:0) after 1m0s
[13:01:08.729] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:25:0)
[13:01:08.729] Timer tick, asking Buildbucket for the build status
[13:01:08.812] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:08.812] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:26:0) after 1m0s
[13:02:08.833] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:26:0)
[13:02:08.833] Timer tick, asking Buildbucket for the build status
[13:02:09.199] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:09.199] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:27:0) after 1m0s
[13:03:09.218] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:27:0)
[13:03:09.218] Timer tick, asking Buildbucket for the build status
[13:03:09.355] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:09.356] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:28:0) after 1m0s
[13:04:09.384] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:28:0)
[13:04:09.384] Timer tick, asking Buildbucket for the build status
[13:04:09.634] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:09.634] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:29:0) after 1m0s
[13:05:09.655] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:29:0)
[13:05:09.655] Timer tick, asking Buildbucket for the build status
[13:05:09.760] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:09.760] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:30:0) after 1m0s
[13:06:09.754] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:30:0)
[13:06:09.754] Timer tick, asking Buildbucket for the build status
[13:06:09.833] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:09.833] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:31:0) after 1m0s
[13:07:09.851] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:31:0)
[13:07:09.851] Timer tick, asking Buildbucket for the build status
[13:07:10.011] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:10.011] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:32:0) after 1m0s
[13:08:10.036] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:32:0)
[13:08:10.036] Timer tick, asking Buildbucket for the build status
[13:08:10.185] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:10.185] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:33:0) after 1m0s
[13:09:10.211] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:33:0)
[13:09:10.211] Timer tick, asking Buildbucket for the build status
[13:09:10.383] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:10.383] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:34:0) after 1m0s
[13:10:10.401] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:34:0)
[13:10:10.401] Timer tick, asking Buildbucket for the build status
[13:10:10.509] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:10.509] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:35:0) after 1m0s
[13:11:10.520] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:35:0)
[13:11:10.520] Timer tick, asking Buildbucket for the build status
[13:11:10.625] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:10.625] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:36:0) after 1m0s
[13:12:10.640] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:36:0)
[13:12:10.640] Timer tick, asking Buildbucket for the build status
[13:12:10.796] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:10.796] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:37:0) after 1m0s
[13:13:10.815] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:37:0)
[13:13:10.815] Timer tick, asking Buildbucket for the build status
[13:13:11.015] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:11.015] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:38:0) after 1m0s
[13:14:11.033] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:38:0)
[13:14:11.033] Timer tick, asking Buildbucket for the build status
[13:14:11.116] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:11.117] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:39:0) after 1m0s
[13:15:11.136] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:39:0)
[13:15:11.136] Timer tick, asking Buildbucket for the build status
[13:15:11.285] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:11.285] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:40:0) after 1m0s
[13:16:11.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:40:0)
[13:16:11.302] Timer tick, asking Buildbucket for the build status
[13:16:11.469] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:11.469] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:41:0) after 1m0s
[13:17:11.488] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:41:0)
[13:17:11.488] Timer tick, asking Buildbucket for the build status
[13:17:11.574] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:11.574] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:42:0) after 1m0s
[13:18:11.596] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:42:0)
[13:18:11.596] Timer tick, asking Buildbucket for the build status
[13:18:11.759] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:11.759] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:43:0) after 1m0s
[13:19:11.784] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:43:0)
[13:19:11.784] Timer tick, asking Buildbucket for the build status
[13:19:11.906] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:11.906] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:44:0) after 1m0s
[13:20:11.905] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:44:0)
[13:20:11.905] Timer tick, asking Buildbucket for the build status
[13:20:11.994] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:11.994] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:45:0) after 1m0s
[13:21:12.014] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:45:0)
[13:21:12.014] Timer tick, asking Buildbucket for the build status
[13:21:12.126] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:12.126] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:46:0) after 1m0s
[13:22:12.175] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:46:0)
[13:22:12.175] Timer tick, asking Buildbucket for the build status
[13:22:12.281] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:12.281] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:47:0) after 1m0s
[13:23:12.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:47:0)
[13:23:12.302] Timer tick, asking Buildbucket for the build status
[13:23:12.384] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:12.384] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:48:0) after 1m0s
[13:24:12.406] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:48:0)
[13:24:12.406] Timer tick, asking Buildbucket for the build status
[13:24:12.496] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:12.496] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:49:0) after 1m0s
[13:25:12.494] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:49:0)
[13:25:12.494] Timer tick, asking Buildbucket for the build status
[13:25:12.662] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:12.662] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:50:0) after 1m0s
[13:26:12.682] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:50:0)
[13:26:12.682] Timer tick, asking Buildbucket for the build status
[13:26:12.763] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:12.763] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:51:0) after 1m0s
[13:27:12.785] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:51:0)
[13:27:12.785] Timer tick, asking Buildbucket for the build status
[13:27:12.929] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:12.929] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:52:0) after 1m0s
[13:28:12.950] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:52:0)
[13:28:12.950] Timer tick, asking Buildbucket for the build status
[13:28:13.051] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:13.051] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:53:0) after 1m0s
[13:29:13.072] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:53:0)
[13:29:13.072] Timer tick, asking Buildbucket for the build status
[13:29:13.167] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:13.167] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:54:0) after 1m0s
[13:30:13.207] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:54:0)
[13:30:13.207] Timer tick, asking Buildbucket for the build status
[13:30:13.313] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:13.313] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:55:0) after 1m0s
[13:31:13.386] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:55:0)
[13:31:13.386] Timer tick, asking Buildbucket for the build status
[13:31:13.782] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:13.782] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:56:0) after 1m0s
[13:32:13.791] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:56:0)
[13:32:13.791] Timer tick, asking Buildbucket for the build status
[13:32:13.878] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:13.878] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:57:0) after 1m0s
[13:33:13.899] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:57:0)
[13:33:13.899] Timer tick, asking Buildbucket for the build status
[13:33:13.981] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:13.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:58:0) after 1m0s
[13:34:14.002] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:58:0)
[13:34:14.002] Timer tick, asking Buildbucket for the build status
[13:34:14.075] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:14.075] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:59:0) after 1m0s
[13:35:14.107] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:59:0)
[13:35:14.107] Timer tick, asking Buildbucket for the build status
[13:35:14.164] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:14.164] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:60:0) after 1m0s
[13:36:14.185] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:60:0)
[13:36:14.185] Timer tick, asking Buildbucket for the build status
[13:36:14.267] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:14.267] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:61:0) after 1m0s
[13:37:14.287] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:61:0)
[13:37:14.287] Timer tick, asking Buildbucket for the build status
[13:37:14.367] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:14.367] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:62:0) after 1m0s
[13:38:14.406] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:62:0)
[13:38:14.406] Timer tick, asking Buildbucket for the build status
[13:38:14.618] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:14.618] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:63:0) after 1m0s
[13:39:14.638] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:63:0)
[13:39:14.638] Timer tick, asking Buildbucket for the build status
[13:39:14.735] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:14.735] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:64:0) after 1m0s
[13:40:14.752] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:64:0)
[13:40:14.752] Timer tick, asking Buildbucket for the build status
[13:40:14.910] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:14.910] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:65:0) after 1m0s
[13:41:14.975] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:65:0)
[13:41:14.975] Timer tick, asking Buildbucket for the build status
[13:41:15.127] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:15.127] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:66:0) after 1m0s
[13:42:15.128] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:66:0)
[13:42:15.128] Timer tick, asking Buildbucket for the build status
[13:42:15.430] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:15.430] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:67:0) after 1m0s
[13:43:15.450] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:67:0)
[13:43:15.450] Timer tick, asking Buildbucket for the build status
[13:43:15.520] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:15.520] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:68:0) after 1m0s
[13:44:15.518] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:68:0)
[13:44:15.518] Timer tick, asking Buildbucket for the build status
[13:44:15.789] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:15.789] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:69:0) after 1m0s
[13:45:15.813] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:69:0)
[13:45:15.813] Timer tick, asking Buildbucket for the build status
[13:45:15.879] Build 8917469048373175424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:15.879] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:70:0) after 1m0s
[13:46:01.636] Received PubSub notification, asking Buildbucket for the build status
[13:46:01.731] Build 8917469048373175424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:15.908] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:70:0)
[13:46:15.908] Timer tick, asking Buildbucket for the build status
[13:46:16.053] Build 8917469048373175424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:16.053] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9082899110354185712:72:0) after 1m0s
[13:46:33.548] Received PubSub notification, asking Buildbucket for the build status
[13:46:33.599] Build 8917469048373175424: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:46:33.599] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1554039992910059",
"created_by": "project:infra",
"created_ts": "1554035823426860",
"id": "8917469048373175424",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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}, \"bot_id\": \"publish-tarball-0-rncq\", \"buildername\": \"publish_tarball_dispatcher\", \"path_config\": \"generic\", \"recipe\": \"publish_tarball\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"publish_tarball\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"publish-tarball-0-rncq\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554039960966266",
"status": "COMPLETED",
"status_changed_ts": "1554039993298781",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9082899110354185712",
"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/8917469048373175424/+/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:43ec9261414edf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1554039993301954",
"url": "https://ci.chromium.org/b/8917469048373175424",
"utcnow_ts": "1554039993590075"
}
}
[13:46:33.599] Invocation finished in 1h9m31.445005007s with status SUCCEEDED