[14:07:08.059] New invocation is queued and will start shortly
[14:07:09.239] Starting the invocation (attempt 1)
[14:07:09.279] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:07:09.279] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:07:09.279] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9085158366026992576",
"parameters_json": "{\"builder_name\":\"archive\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"ebcf508af11a6490fc8d2e04cee12426f2642775\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:archive",
"scheduler_invocation_id:9085158366026992576",
"scheduler_job_id:r8/archive",
"user_agent:luci-scheduler",
"buildset:commit/git/ebcf508af11a6490fc8d2e04cee12426f2642775",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ebcf508af11a6490fc8d2e04cee12426f2642775",
"gitiles_ref:refs/heads/master"
]
}
[14:07:11.603] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551881229482044",
"id": "8919728303872622512",
"lease_expiration_ts": "1551991031229365",
"lease_key": "981009188",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"ebcf508af11a6490fc8d2e04cee12426f2642775\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551881231454039",
"tags": [
"build_address:luci.r8.ci/archive/56",
"builder:archive",
"buildset:commit/git/ebcf508af11a6490fc8d2e04cee12426f2642775",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ebcf508af11a6490fc8d2e04cee12426f2642775",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085158366026992576",
"scheduler_job_id:r8/archive",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8919728303872622512/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:436c25e1a0d11a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551881231454132",
"url": "https://ci.chromium.org/b/8919728303872622512",
"utcnow_ts": "1551881231594212"
}
}
[14:07:11.603] Task URL: https://ci.chromium.org/b/8919728303872622512
[14:07:11.603] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:2:0) after 1m0s
[14:08:11.623] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:2:0)
[14:08:11.623] Timer tick, asking Buildbucket for the build status
[14:08:11.746] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:11.746] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:3:0) after 1m0s
[14:09:11.775] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:3:0)
[14:09:11.775] Timer tick, asking Buildbucket for the build status
[14:09:11.949] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:11.949] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:4:0) after 1m0s
[14:10:11.971] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:4:0)
[14:10:11.971] Timer tick, asking Buildbucket for the build status
[14:10:12.103] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:12.103] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:5:0) after 1m0s
[14:11:12.114] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:5:0)
[14:11:12.114] Timer tick, asking Buildbucket for the build status
[14:11:12.247] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:12.247] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:6:0) after 1m0s
[14:12:12.264] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:6:0)
[14:12:12.264] Timer tick, asking Buildbucket for the build status
[14:12:12.361] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:12.361] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:7:0) after 1m0s
[14:13:12.387] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:7:0)
[14:13:12.387] Timer tick, asking Buildbucket for the build status
[14:13:12.864] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:12.864] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:8:0) after 1m0s
[14:14:12.891] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:8:0)
[14:14:12.891] Timer tick, asking Buildbucket for the build status
[14:14:13.012] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:13.012] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:9:0) after 1m0s
[14:15:13.031] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:9:0)
[14:15:13.031] Timer tick, asking Buildbucket for the build status
[14:15:13.084] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:13.084] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:10:0) after 1m0s
[14:16:13.104] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:10:0)
[14:16:13.104] Timer tick, asking Buildbucket for the build status
[14:16:13.287] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:13.287] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:11:0) after 1m0s
[14:17:13.281] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:11:0)
[14:17:13.281] Timer tick, asking Buildbucket for the build status
[14:17:13.387] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:13.387] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:12:0) after 1m0s
[14:18:13.375] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:12:0)
[14:18:13.375] Timer tick, asking Buildbucket for the build status
[14:18:13.493] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:13.493] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:13:0) after 1m0s
[14:19:13.521] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:13:0)
[14:19:13.521] Timer tick, asking Buildbucket for the build status
[14:19:13.594] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:13.594] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:14:0) after 1m0s
[14:20:13.578] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:14:0)
[14:20:13.578] Timer tick, asking Buildbucket for the build status
[14:20:13.635] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:13.635] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:15:0) after 1m0s
[14:21:13.621] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:15:0)
[14:21:13.621] Timer tick, asking Buildbucket for the build status
[14:21:13.671] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:21:13.671] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:16:0) after 1m0s
[14:22:13.686] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:16:0)
[14:22:13.686] Timer tick, asking Buildbucket for the build status
[14:22:13.766] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:13.766] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:17:0) after 1m0s
[14:23:13.825] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:17:0)
[14:23:13.825] Timer tick, asking Buildbucket for the build status
[14:23:13.978] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:13.978] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:18:0) after 1m0s
[14:24:13.954] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:18:0)
[14:24:13.954] Timer tick, asking Buildbucket for the build status
[14:24:14.003] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:14.003] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:19:0) after 1m0s
[14:25:14.012] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:19:0)
[14:25:14.012] Timer tick, asking Buildbucket for the build status
[14:25:14.081] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:14.081] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:20:0) after 1m0s
[14:26:14.100] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:20:0)
[14:26:14.100] Timer tick, asking Buildbucket for the build status
[14:26:14.159] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:14.159] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:21:0) after 1m0s
[14:27:14.177] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:21:0)
[14:27:14.177] Timer tick, asking Buildbucket for the build status
[14:27:14.237] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:14.237] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:22:0) after 1m0s
[14:28:14.259] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:22:0)
[14:28:14.259] Timer tick, asking Buildbucket for the build status
[14:28:14.327] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:14.327] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:23:0) after 1m0s
[14:29:14.352] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:23:0)
[14:29:14.352] Timer tick, asking Buildbucket for the build status
[14:29:14.435] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:14.435] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:24:0) after 1m0s
[14:30:14.443] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:24:0)
[14:30:14.443] Timer tick, asking Buildbucket for the build status
[14:30:14.522] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:14.522] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:25:0) after 1m0s
[14:31:14.509] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:25:0)
[14:31:14.509] Timer tick, asking Buildbucket for the build status
[14:31:14.560] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:14.560] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:26:0) after 1m0s
[14:32:14.640] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:26:0)
[14:32:14.640] Timer tick, asking Buildbucket for the build status
[14:32:14.702] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:14.702] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:27:0) after 1m0s
[14:33:14.724] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:27:0)
[14:33:14.724] Timer tick, asking Buildbucket for the build status
[14:33:14.769] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:14.769] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:28:0) after 1m0s
[14:34:14.752] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:28:0)
[14:34:14.752] Timer tick, asking Buildbucket for the build status
[14:34:14.799] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:14.799] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:29:0) after 1m0s
[14:35:14.818] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:29:0)
[14:35:14.818] Timer tick, asking Buildbucket for the build status
[14:35:14.868] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:14.868] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:30:0) after 1m0s
[14:36:14.892] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:30:0)
[14:36:14.892] Timer tick, asking Buildbucket for the build status
[14:36:15.067] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:15.067] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:31:0) after 1m0s
[14:37:15.084] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:31:0)
[14:37:15.084] Timer tick, asking Buildbucket for the build status
[14:37:15.138] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:15.138] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:32:0) after 1m0s
[14:38:15.150] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:32:0)
[14:38:15.150] Timer tick, asking Buildbucket for the build status
[14:38:15.261] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:15.261] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:33:0) after 1m0s
[14:39:15.280] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:33:0)
[14:39:15.280] Timer tick, asking Buildbucket for the build status
[14:39:15.394] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:15.394] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:34:0) after 1m0s
[14:40:15.388] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:34:0)
[14:40:15.388] Timer tick, asking Buildbucket for the build status
[14:40:15.437] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:15.437] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:35:0) after 1m0s
[14:41:15.456] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:35:0)
[14:41:15.456] Timer tick, asking Buildbucket for the build status
[14:41:15.556] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:15.556] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:36:0) after 1m0s
[14:42:15.577] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:36:0)
[14:42:15.577] Timer tick, asking Buildbucket for the build status
[14:42:15.624] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:15.624] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:37:0) after 1m0s
[14:43:15.617] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:37:0)
[14:43:15.617] Timer tick, asking Buildbucket for the build status
[14:43:15.665] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:15.665] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:38:0) after 1m0s
[14:44:15.689] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:38:0)
[14:44:15.689] Timer tick, asking Buildbucket for the build status
[14:44:15.733] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:15.733] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:39:0) after 1m0s
[14:45:15.759] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:39:0)
[14:45:15.759] Timer tick, asking Buildbucket for the build status
[14:45:15.828] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:15.828] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:40:0) after 1m0s
[14:46:15.850] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:40:0)
[14:46:15.850] Timer tick, asking Buildbucket for the build status
[14:46:15.906] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:15.906] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:41:0) after 1m0s
[14:47:15.924] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:41:0)
[14:47:15.924] Timer tick, asking Buildbucket for the build status
[14:47:16.237] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:47:16.237] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:42:0) after 1m0s
[14:48:16.258] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:42:0)
[14:48:16.258] Timer tick, asking Buildbucket for the build status
[14:48:16.375] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:48:16.375] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:43:0) after 1m0s
[14:49:16.379] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:43:0)
[14:49:16.379] Timer tick, asking Buildbucket for the build status
[14:49:16.513] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:49:16.513] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:44:0) after 1m0s
[14:50:16.505] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:44:0)
[14:50:16.505] Timer tick, asking Buildbucket for the build status
[14:50:16.680] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:50:16.680] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:45:0) after 1m0s
[14:51:16.702] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:45:0)
[14:51:16.702] Timer tick, asking Buildbucket for the build status
[14:51:16.757] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:51:16.757] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:46:0) after 1m0s
[14:52:16.735] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:46:0)
[14:52:16.735] Timer tick, asking Buildbucket for the build status
[14:52:16.784] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:52:16.784] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:47:0) after 1m0s
[14:53:16.802] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:47:0)
[14:53:16.802] Timer tick, asking Buildbucket for the build status
[14:53:16.867] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:53:16.867] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:48:0) after 1m0s
[14:54:16.907] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:48:0)
[14:54:16.907] Timer tick, asking Buildbucket for the build status
[14:54:17.076] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:54:17.076] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:49:0) after 1m0s
[14:55:17.081] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:49:0)
[14:55:17.081] Timer tick, asking Buildbucket for the build status
[14:55:17.146] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:55:17.146] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:50:0) after 1m0s
[14:56:17.201] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:50:0)
[14:56:17.201] Timer tick, asking Buildbucket for the build status
[14:56:17.371] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:56:17.371] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:51:0) after 1m0s
[14:57:17.388] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:51:0)
[14:57:17.388] Timer tick, asking Buildbucket for the build status
[14:57:17.513] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:57:17.513] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:52:0) after 1m0s
[14:58:17.510] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:52:0)
[14:58:17.510] Timer tick, asking Buildbucket for the build status
[14:58:17.602] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:58:17.602] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:53:0) after 1m0s
[14:59:17.618] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:53:0)
[14:59:17.618] Timer tick, asking Buildbucket for the build status
[14:59:17.720] Build 8919728303872622512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:59:17.720] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:54:0) after 1m0s
[15:00:14.055] Received PubSub notification, asking Buildbucket for the build status
[15:00:14.099] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:17.741] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:54:0)
[15:00:17.741] Timer tick, asking Buildbucket for the build status
[15:00:18.204] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:18.204] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:56:0) after 1m0s
[15:01:18.188] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:56:0)
[15:01:18.188] Timer tick, asking Buildbucket for the build status
[15:01:18.502] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:18.502] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:57:0) after 1m0s
[15:02:18.538] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:57:0)
[15:02:18.538] Timer tick, asking Buildbucket for the build status
[15:02:18.591] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:18.591] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:58:0) after 1m0s
[15:03:18.653] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:58:0)
[15:03:18.653] Timer tick, asking Buildbucket for the build status
[15:03:18.738] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:18.738] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:59:0) after 1m0s
[15:04:18.734] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:59:0)
[15:04:18.734] Timer tick, asking Buildbucket for the build status
[15:04:18.782] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:18.782] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:60:0) after 1m0s
[15:05:18.800] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:60:0)
[15:05:18.800] Timer tick, asking Buildbucket for the build status
[15:05:18.875] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:18.875] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:61:0) after 1m0s
[15:06:18.884] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:61:0)
[15:06:18.884] Timer tick, asking Buildbucket for the build status
[15:06:18.930] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:18.930] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:62:0) after 1m0s
[15:07:18.959] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:62:0)
[15:07:18.959] Timer tick, asking Buildbucket for the build status
[15:07:19.010] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:19.010] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:63:0) after 1m0s
[15:08:18.998] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:63:0)
[15:08:18.998] Timer tick, asking Buildbucket for the build status
[15:08:19.199] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:19.199] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:64:0) after 1m0s
[15:09:19.219] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:64:0)
[15:09:19.219] Timer tick, asking Buildbucket for the build status
[15:09:19.269] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:19.269] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:65:0) after 1m0s
[15:10:19.299] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:65:0)
[15:10:19.299] Timer tick, asking Buildbucket for the build status
[15:10:19.339] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:19.339] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:66:0) after 1m0s
[15:11:19.361] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:66:0)
[15:11:19.361] Timer tick, asking Buildbucket for the build status
[15:11:19.488] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:19.488] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:67:0) after 1m0s
[15:12:19.511] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:67:0)
[15:12:19.511] Timer tick, asking Buildbucket for the build status
[15:12:19.603] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:19.603] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:68:0) after 1m0s
[15:13:19.623] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:68:0)
[15:13:19.623] Timer tick, asking Buildbucket for the build status
[15:13:19.664] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:19.664] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:69:0) after 1m0s
[15:14:19.685] Handling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:69:0)
[15:14:19.685] Timer tick, asking Buildbucket for the build status
[15:14:19.726] Build 8919728303872622512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:19.726] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085158366026992576:70:0) after 1m0s
[15:15:04.243] Received PubSub notification, asking Buildbucket for the build status
[15:15:04.279] Build 8919728303872622512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:15:04.279] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"completed_ts": "1551885302815411",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551881229482044",
"id": "8919728303872622512",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"ebcf508af11a6490fc8d2e04cee12426f2642775\"}}",
"project": "r8",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"chrome-xenial-18041000-0b3ff326a44\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4143-92e279d\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"id\": [\"gce-xenial-396d8f4b-us-east1-b-q3tg\"], \"pool\": [\"luci.r8.ci\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"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}, \"repository\": \"https://r8.googlesource.com/r8\", \"buildername\": \"archive\", \"recipe\": \"rex\", \"mastername\": \"internal.client.r8\", \"buildnumber\": 56, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"ebcf508af11a6490fc8d2e04cee12426f2642775\", \"archive\": \"True\", \"bot_id\": \"gce-xenial-396d8f4b-us-east1-b-q3tg\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551884413393498",
"status": "COMPLETED",
"status_changed_ts": "1551885303925280",
"tags": [
"build_address:luci.r8.ci/archive/56",
"builder:archive",
"buildset:commit/git/ebcf508af11a6490fc8d2e04cee12426f2642775",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/ebcf508af11a6490fc8d2e04cee12426f2642775",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085158366026992576",
"scheduler_job_id:r8/archive",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8919728303872622512/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:436c25e1a0d11a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551885303926563",
"url": "https://ci.chromium.org/b/8919728303872622512",
"utcnow_ts": "1551885304271670"
}
}
[15:15:04.279] Invocation finished in 1h7m56.234676469s with status SUCCEEDED