[17:54:22.320] New invocation is queued and will start shortly
[17:54:23.429] Starting the invocation (attempt 1)
[17:54:23.470] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:54:23.470] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:54:23.471] Buildbucket request:
{
"bucket": "luci.v8.ci.br.beta",
"client_operation_id": "9038305437861805408",
"parameters_json": "{\"builder_name\":\"V8 Win64 - debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/branch-heads/8.5\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.5@dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\",\"title\":\"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}]},\"branch\":\"refs/branch-heads/8.5\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Win64 - debug",
"scheduler_invocation_id:9038305437861805408",
"scheduler_job_id:v8/ci.br.beta-V8 Win64 - debug",
"user_agent:luci-scheduler",
"buildset:commit/git/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"gitiles_ref:refs/branch-heads/8.5"
]
}
[17:54:24.316] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1596563663604563",
"id": "8872875375830510976",
"parameters_json": "{\"builder_name\": \"V8 Win64 - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.5\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.5@dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"title\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}]}, \"branch\": \"refs/branch-heads/8.5\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596563663948329",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Win64 - debug/37",
"builder:V8 Win64 - debug",
"buildset:commit/git/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"gitiles_ref:refs/branch-heads/8.5",
"scheduler_invocation_id:9038305437861805408",
"scheduler_job_id:v8/ci.br.beta-V8 Win64 - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8872875375830510976/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1596563663948459",
"url": "https://ci.chromium.org/b/8872875375830510976",
"utcnow_ts": "1596563664308776"
}
}
[17:54:24.316] Task URL: https://ci.chromium.org/b/8872875375830510976
[17:54:24.316] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:2:0) after 1m0s
[17:55:24.616] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:2:0)
[17:55:24.616] Timer tick, asking Buildbucket for the build status
[17:55:24.731] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:24.731] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:3:0) after 1m0s
[17:56:24.821] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:3:0)
[17:56:24.821] Timer tick, asking Buildbucket for the build status
[17:56:24.932] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:24.932] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:4:0) after 1m0s
[17:57:24.953] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:4:0)
[17:57:24.953] Timer tick, asking Buildbucket for the build status
[17:57:25.058] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:25.058] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:5:0) after 1m0s
[17:58:25.111] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:5:0)
[17:58:25.111] Timer tick, asking Buildbucket for the build status
[17:58:25.309] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:58:25.309] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:6:0) after 1m0s
[17:59:25.377] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:6:0)
[17:59:25.377] Timer tick, asking Buildbucket for the build status
[17:59:25.786] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:25.786] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:7:0) after 1m0s
[18:00:25.843] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:7:0)
[18:00:25.843] Timer tick, asking Buildbucket for the build status
[18:00:25.918] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:25.918] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:8:0) after 1m0s
[18:01:25.953] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:8:0)
[18:01:25.953] Timer tick, asking Buildbucket for the build status
[18:01:26.047] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:26.047] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:9:0) after 1m0s
[18:02:26.087] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:9:0)
[18:02:26.087] Timer tick, asking Buildbucket for the build status
[18:02:26.463] Build 8872875375830510976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:26.463] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:10:0) after 1m0s
[18:03:00.772] Received PubSub notification, asking Buildbucket for the build status
[18:03:00.932] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:26.509] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:10:0)
[18:03:26.509] Timer tick, asking Buildbucket for the build status
[18:03:26.718] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:26.718] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:12:0) after 1m0s
[18:04:26.738] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:12:0)
[18:04:26.738] Timer tick, asking Buildbucket for the build status
[18:04:26.928] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:26.928] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:13:0) after 1m0s
[18:05:26.952] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:13:0)
[18:05:26.952] Timer tick, asking Buildbucket for the build status
[18:05:27.241] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:27.241] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:14:0) after 1m0s
[18:06:27.298] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:14:0)
[18:06:27.298] Timer tick, asking Buildbucket for the build status
[18:06:27.357] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:27.357] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:15:0) after 1m0s
[18:07:27.340] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:15:0)
[18:07:27.340] Timer tick, asking Buildbucket for the build status
[18:07:27.440] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:27.440] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:16:0) after 1m0s
[18:08:27.536] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:16:0)
[18:08:27.536] Timer tick, asking Buildbucket for the build status
[18:08:27.993] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:27.993] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:17:0) after 1m0s
[18:09:28.083] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:17:0)
[18:09:28.083] Timer tick, asking Buildbucket for the build status
[18:09:28.724] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:28.725] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:18:0) after 1m0s
[18:10:28.754] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:18:0)
[18:10:28.754] Timer tick, asking Buildbucket for the build status
[18:10:28.822] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:28.822] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:19:0) after 1m0s
[18:11:28.871] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:19:0)
[18:11:28.871] Timer tick, asking Buildbucket for the build status
[18:11:29.204] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:29.205] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:20:0) after 1m0s
[18:12:29.243] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:20:0)
[18:12:29.243] Timer tick, asking Buildbucket for the build status
[18:12:29.310] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:29.310] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:21:0) after 1m0s
[18:13:29.323] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:21:0)
[18:13:29.323] Timer tick, asking Buildbucket for the build status
[18:13:29.407] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:29.407] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:22:0) after 1m0s
[18:14:29.418] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:22:0)
[18:14:29.418] Timer tick, asking Buildbucket for the build status
[18:14:29.916] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:29.916] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:23:0) after 1m0s
[18:15:29.939] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:23:0)
[18:15:29.939] Timer tick, asking Buildbucket for the build status
[18:15:30.126] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:30.126] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:24:0) after 1m0s
[18:16:30.256] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:24:0)
[18:16:30.256] Timer tick, asking Buildbucket for the build status
[18:16:30.402] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:30.402] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:25:0) after 1m0s
[18:17:30.808] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:25:0)
[18:17:30.808] Timer tick, asking Buildbucket for the build status
[18:17:30.928] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:30.928] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:26:0) after 1m0s
[18:18:31.148] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:26:0)
[18:18:31.148] Timer tick, asking Buildbucket for the build status
[18:18:31.238] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:31.238] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:27:0) after 1m0s
[18:19:31.376] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:27:0)
[18:19:31.376] Timer tick, asking Buildbucket for the build status
[18:19:31.630] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:31.630] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:28:0) after 1m0s
[18:20:31.869] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:28:0)
[18:20:31.869] Timer tick, asking Buildbucket for the build status
[18:20:31.951] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:31.951] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:29:0) after 1m0s
[18:21:32.175] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:29:0)
[18:21:32.175] Timer tick, asking Buildbucket for the build status
[18:21:32.293] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:32.293] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:30:0) after 1m0s
[18:22:32.387] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:30:0)
[18:22:32.387] Timer tick, asking Buildbucket for the build status
[18:22:32.641] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:32.641] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:31:0) after 1m0s
[18:23:32.766] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:31:0)
[18:23:32.766] Timer tick, asking Buildbucket for the build status
[18:23:32.870] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:32.870] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:32:0) after 1m0s
[18:24:33.002] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:32:0)
[18:24:33.002] Timer tick, asking Buildbucket for the build status
[18:24:33.123] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:33.123] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:33:0) after 1m0s
[18:25:33.187] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:33:0)
[18:25:33.187] Timer tick, asking Buildbucket for the build status
[18:25:33.354] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:33.354] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:34:0) after 1m0s
[18:26:33.574] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:34:0)
[18:26:33.574] Timer tick, asking Buildbucket for the build status
[18:26:33.772] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:33.772] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:35:0) after 1m0s
[18:27:33.863] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:35:0)
[18:27:33.863] Timer tick, asking Buildbucket for the build status
[18:27:33.962] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:33.962] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:36:0) after 1m0s
[18:28:34.167] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:36:0)
[18:28:34.167] Timer tick, asking Buildbucket for the build status
[18:28:34.312] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:34.312] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:37:0) after 1m0s
[18:29:34.374] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:37:0)
[18:29:34.374] Timer tick, asking Buildbucket for the build status
[18:29:35.046] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:35.046] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:38:0) after 1m0s
[18:30:35.125] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:38:0)
[18:30:35.125] Timer tick, asking Buildbucket for the build status
[18:30:35.681] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:35.681] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:39:0) after 1m0s
[18:31:35.773] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:39:0)
[18:31:35.773] Timer tick, asking Buildbucket for the build status
[18:31:36.074] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:36.074] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:40:0) after 1m0s
[18:32:36.204] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:40:0)
[18:32:36.204] Timer tick, asking Buildbucket for the build status
[18:32:36.316] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:36.316] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:41:0) after 1m0s
[18:33:36.336] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:41:0)
[18:33:36.336] Timer tick, asking Buildbucket for the build status
[18:33:36.547] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:36.547] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:42:0) after 1m0s
[18:34:36.685] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:42:0)
[18:34:36.685] Timer tick, asking Buildbucket for the build status
[18:34:36.909] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:36.909] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:43:0) after 1m0s
[18:35:37.084] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:43:0)
[18:35:37.084] Timer tick, asking Buildbucket for the build status
[18:35:37.222] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:37.223] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:44:0) after 1m0s
[18:36:37.341] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:44:0)
[18:36:37.341] Timer tick, asking Buildbucket for the build status
[18:36:37.424] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:37.424] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:45:0) after 1m0s
[18:37:37.537] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:45:0)
[18:37:37.537] Timer tick, asking Buildbucket for the build status
[18:37:37.727] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:37.727] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:46:0) after 1m0s
[18:38:37.765] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:46:0)
[18:38:37.765] Timer tick, asking Buildbucket for the build status
[18:38:38.063] Build 8872875375830510976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:38.063] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64 - debug:9038305437861805408:47:0) after 1m0s
[18:38:38.507] Received PubSub notification, asking Buildbucket for the build status
[18:38:38.749] Build 8872875375830510976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:38:38.749] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"completed_ts": "1596566317168557",
"created_by": "project:v8",
"created_ts": "1596563663604563",
"id": "8872875375830510976",
"parameters_json": "{\"builder_name\": \"V8 Win64 - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.5\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.5@dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"title\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}]}, \"branch\": \"refs/branch-heads/8.5\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.5\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.5@dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"title\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}]}, \"bot_id\": \"luci-v8-ci-win10-5-m0aj\", \"branch\": \"refs/branch-heads/8.5\", \"build_config\": \"Debug\", \"buildername\": \"V8 Win64 - debug\", \"buildnumber\": 37, \"got_revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\", \"got_revision_cp\": \"refs/branch-heads/8.5@{#38}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.v8\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_2e252b29e281567c3d94a0c93eca4fecc3793634c4b377a59880ad04c13ac883_v2\", \"builder_af30e7f06bc0932ce748ea06875a780b23648f5432d61376bcfee3b652d89265_v2\", \"builder_fff444379d7d79621201b8d2482aa71fbde17e3911295a4e3af2d054e11934c5_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-v8-ci-win10-5-m0aj\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1596564179958069",
"status": "COMPLETED",
"status_changed_ts": "1596566317796999",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Win64 - debug/37",
"builder:V8 Win64 - debug",
"buildset:commit/git/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/dc26f2d49c52b0c0e81c9f5f06f7347b03ef9b64",
"gitiles_ref:refs/branch-heads/8.5",
"scheduler_invocation_id:9038305437861805408",
"scheduler_job_id:v8/ci.br.beta-V8 Win64 - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8872875375830510976/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4dd36db69e2f8a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1596566317927378",
"url": "https://ci.chromium.org/b/8872875375830510976",
"utcnow_ts": "1596566318717359"
}
}
[18:38:38.749] Invocation finished in 44m16.465512511s with status SUCCEEDED