[09:58:15.250] New invocation is queued and will start shortly
[09:58:16.466] Starting the invocation (attempt 1)
[09:58:16.537] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:58:16.537] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:58:16.537] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028822711114691920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4e9da022508c5f73e027620d449f4196143183cf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028822711114691920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4e9da022508c5f73e027620d449f4196143183cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e9da022508c5f73e027620d449f4196143183cf",
"gitiles_ref:refs/heads/master"
]
}
[09:58:17.461] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605607096689611",
"id": "8863392648940714944",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e9da022508c5f73e027620d449f4196143183cf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605607097180919",
"tags": [
"build_address:luci.chromium.ci/win-asan/15684",
"builder:win-asan",
"buildset:commit/git/4e9da022508c5f73e027620d449f4196143183cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e9da022508c5f73e027620d449f4196143183cf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028822711114691920",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863392648940714944/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1605607097181032",
"url": "https://ci.chromium.org/b/8863392648940714944",
"utcnow_ts": "1605607097451282"
}
}
[09:58:17.461] Task URL: https://ci.chromium.org/b/8863392648940714944
[09:58:17.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:2:0) after 1m0s
[09:59:17.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:2:0)
[09:59:17.502] Timer tick, asking Buildbucket for the build status
[09:59:17.598] Build 8863392648940714944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:17.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:3:0) after 1m0s
[10:00:17.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:3:0)
[10:00:17.615] Timer tick, asking Buildbucket for the build status
[10:00:17.843] Build 8863392648940714944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:17.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:4:0) after 1m0s
[10:01:17.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:4:0)
[10:01:17.846] Timer tick, asking Buildbucket for the build status
[10:01:18.105] Build 8863392648940714944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:18.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:5:0) after 1m0s
[10:02:18.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:5:0)
[10:02:18.136] Timer tick, asking Buildbucket for the build status
[10:02:18.246] Build 8863392648940714944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:18.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:6:0) after 1m0s
[10:03:18.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:6:0)
[10:03:18.271] Timer tick, asking Buildbucket for the build status
[10:03:18.576] Build 8863392648940714944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:18.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:7:0) after 1m0s
[10:03:20.804] Received PubSub notification, asking Buildbucket for the build status
[10:03:20.884] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:18.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:7:0)
[10:04:18.640] Timer tick, asking Buildbucket for the build status
[10:04:18.715] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:18.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:9:0) after 1m0s
[10:05:18.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:9:0)
[10:05:18.789] Timer tick, asking Buildbucket for the build status
[10:05:18.899] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:18.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:10:0) after 1m0s
[10:06:18.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:10:0)
[10:06:18.912] Timer tick, asking Buildbucket for the build status
[10:06:19.022] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:19.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:11:0) after 1m0s
[10:07:19.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:11:0)
[10:07:19.048] Timer tick, asking Buildbucket for the build status
[10:07:20.713] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:20.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:12:0) after 1m0s
[10:08:20.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:12:0)
[10:08:20.735] Timer tick, asking Buildbucket for the build status
[10:08:20.849] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:20.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:13:0) after 1m0s
[10:09:20.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:13:0)
[10:09:20.872] Timer tick, asking Buildbucket for the build status
[10:09:20.954] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:20.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:14:0) after 1m0s
[10:10:20.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:14:0)
[10:10:20.982] Timer tick, asking Buildbucket for the build status
[10:10:21.052] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:21.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:15:0) after 1m0s
[10:11:21.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:15:0)
[10:11:21.098] Timer tick, asking Buildbucket for the build status
[10:11:21.167] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:21.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:16:0) after 1m0s
[10:12:21.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:16:0)
[10:12:21.244] Timer tick, asking Buildbucket for the build status
[10:12:21.312] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:21.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:17:0) after 1m0s
[10:13:21.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:17:0)
[10:13:21.357] Timer tick, asking Buildbucket for the build status
[10:13:21.641] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:21.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:18:0) after 1m0s
[10:14:21.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:18:0)
[10:14:21.691] Timer tick, asking Buildbucket for the build status
[10:14:22.287] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:22.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:19:0) after 1m0s
[10:15:22.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:19:0)
[10:15:22.371] Timer tick, asking Buildbucket for the build status
[10:15:22.502] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:22.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:20:0) after 1m0s
[10:16:22.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:20:0)
[10:16:22.573] Timer tick, asking Buildbucket for the build status
[10:16:22.635] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:22.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:21:0) after 1m0s
[10:17:22.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:21:0)
[10:17:22.666] Timer tick, asking Buildbucket for the build status
[10:17:22.896] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:22.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:22:0) after 1m0s
[10:18:22.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:22:0)
[10:18:22.917] Timer tick, asking Buildbucket for the build status
[10:18:23.107] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:23.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:23:0) after 1m0s
[10:19:23.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:23:0)
[10:19:23.139] Timer tick, asking Buildbucket for the build status
[10:19:23.257] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:23.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:24:0) after 1m0s
[10:20:23.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:24:0)
[10:20:23.332] Timer tick, asking Buildbucket for the build status
[10:20:23.462] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:23.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:25:0) after 1m0s
[10:21:23.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:25:0)
[10:21:23.517] Timer tick, asking Buildbucket for the build status
[10:21:23.640] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:23.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:26:0) after 1m0s
[10:22:23.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:26:0)
[10:22:23.670] Timer tick, asking Buildbucket for the build status
[10:22:24.122] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:24.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:27:0) after 1m0s
[10:23:24.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:27:0)
[10:23:24.149] Timer tick, asking Buildbucket for the build status
[10:23:24.295] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:24.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:28:0) after 1m0s
[10:24:24.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:28:0)
[10:24:24.324] Timer tick, asking Buildbucket for the build status
[10:24:24.526] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:24.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:29:0) after 1m0s
[10:25:24.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:29:0)
[10:25:24.557] Timer tick, asking Buildbucket for the build status
[10:25:24.688] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:24.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:30:0) after 1m0s
[10:26:24.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:30:0)
[10:26:24.735] Timer tick, asking Buildbucket for the build status
[10:26:24.957] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:24.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:31:0) after 1m0s
[10:27:24.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:31:0)
[10:27:24.981] Timer tick, asking Buildbucket for the build status
[10:27:25.355] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:25.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:32:0) after 1m0s
[10:28:25.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:32:0)
[10:28:25.355] Timer tick, asking Buildbucket for the build status
[10:28:25.466] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:25.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:33:0) after 1m0s
[10:29:25.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:33:0)
[10:29:25.477] Timer tick, asking Buildbucket for the build status
[10:29:25.827] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:25.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:34:0) after 1m0s
[10:30:25.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:34:0)
[10:30:25.930] Timer tick, asking Buildbucket for the build status
[10:30:26.303] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:26.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:35:0) after 1m0s
[10:31:26.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:35:0)
[10:31:26.463] Timer tick, asking Buildbucket for the build status
[10:31:26.632] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:26.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:36:0) after 1m0s
[10:32:26.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:36:0)
[10:32:26.656] Timer tick, asking Buildbucket for the build status
[10:32:26.741] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:26.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:37:0) after 1m0s
[10:33:26.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:37:0)
[10:33:26.770] Timer tick, asking Buildbucket for the build status
[10:33:26.904] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:26.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:38:0) after 1m0s
[10:34:26.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:38:0)
[10:34:26.933] Timer tick, asking Buildbucket for the build status
[10:34:27.058] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:27.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:39:0) after 1m0s
[10:35:27.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:39:0)
[10:35:27.192] Timer tick, asking Buildbucket for the build status
[10:35:27.317] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:27.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:40:0) after 1m0s
[10:36:27.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:40:0)
[10:36:27.334] Timer tick, asking Buildbucket for the build status
[10:36:27.417] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:27.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:41:0) after 1m0s
[10:37:29.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:41:0)
[10:37:29.008] Timer tick, asking Buildbucket for the build status
[10:37:29.143] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:29.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:42:0) after 1m0s
[10:38:29.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:42:0)
[10:38:29.181] Timer tick, asking Buildbucket for the build status
[10:38:29.954] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:29.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:43:0) after 1m0s
[10:39:30.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:43:0)
[10:39:30.194] Timer tick, asking Buildbucket for the build status
[10:39:30.299] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:30.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:44:0) after 1m0s
[10:40:30.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:44:0)
[10:40:30.457] Timer tick, asking Buildbucket for the build status
[10:40:30.533] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:30.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:45:0) after 1m0s
[10:41:30.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:45:0)
[10:41:30.554] Timer tick, asking Buildbucket for the build status
[10:41:30.677] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:30.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:46:0) after 1m0s
[10:42:30.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:46:0)
[10:42:30.758] Timer tick, asking Buildbucket for the build status
[10:42:30.874] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:30.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:47:0) after 1m0s
[10:43:30.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:47:0)
[10:43:30.967] Timer tick, asking Buildbucket for the build status
[10:43:31.313] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:31.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:48:0) after 1m0s
[10:44:31.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:48:0)
[10:44:31.347] Timer tick, asking Buildbucket for the build status
[10:44:31.431] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:31.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:49:0) after 1m0s
[10:45:31.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:49:0)
[10:45:31.542] Timer tick, asking Buildbucket for the build status
[10:45:31.637] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:31.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:50:0) after 1m0s
[10:46:31.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:50:0)
[10:46:31.769] Timer tick, asking Buildbucket for the build status
[10:46:31.901] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:31.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:51:0) after 1m0s
[10:47:31.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:51:0)
[10:47:31.952] Timer tick, asking Buildbucket for the build status
[10:47:32.087] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:32.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:52:0) after 1m0s
[10:48:32.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:52:0)
[10:48:32.205] Timer tick, asking Buildbucket for the build status
[10:48:32.812] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:32.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:53:0) after 1m0s
[10:49:32.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:53:0)
[10:49:32.887] Timer tick, asking Buildbucket for the build status
[10:49:33.105] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:33.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:54:0) after 1m0s
[10:50:33.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:54:0)
[10:50:33.164] Timer tick, asking Buildbucket for the build status
[10:50:33.713] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:33.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:55:0) after 1m0s
[10:51:33.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:55:0)
[10:51:33.712] Timer tick, asking Buildbucket for the build status
[10:51:34.058] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:34.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:56:0) after 1m0s
[10:52:34.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:56:0)
[10:52:34.126] Timer tick, asking Buildbucket for the build status
[10:52:34.237] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:34.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:57:0) after 1m0s
[10:53:34.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:57:0)
[10:53:34.246] Timer tick, asking Buildbucket for the build status
[10:53:34.810] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:34.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:58:0) after 1m0s
[10:54:34.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:58:0)
[10:54:34.915] Timer tick, asking Buildbucket for the build status
[10:54:35.427] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:35.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:59:0) after 1m0s
[10:55:35.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:59:0)
[10:55:35.425] Timer tick, asking Buildbucket for the build status
[10:55:37.080] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:37.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:60:0) after 1m0s
[10:56:37.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:60:0)
[10:56:37.151] Timer tick, asking Buildbucket for the build status
[10:56:37.412] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:37.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:61:0) after 1m0s
[10:57:37.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:61:0)
[10:57:37.391] Timer tick, asking Buildbucket for the build status
[10:57:37.461] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:37.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:62:0) after 1m0s
[10:58:37.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:62:0)
[10:58:37.483] Timer tick, asking Buildbucket for the build status
[10:58:37.614] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:37.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:63:0) after 1m0s
[10:59:37.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:63:0)
[10:59:37.639] Timer tick, asking Buildbucket for the build status
[10:59:37.881] Build 8863392648940714944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:37.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028822711114691920:64:0) after 1m0s
[11:00:36.165] Received PubSub notification, asking Buildbucket for the build status
[11:00:36.324] Build 8863392648940714944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:00:36.324] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605610833694181",
"created_by": "project:chromium",
"created_ts": "1605607096689611",
"id": "8863392648940714944",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e9da022508c5f73e027620d449f4196143183cf\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"4e9da022508c5f73e027620d449f4196143183cf\", \"project\": \"chromium/src\"}], \"$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}, \"bot_id\": \"luci-chromium-ci-win10-32-5-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15684, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"4f96bf19941b3842126eb047d0573a053648bbdc\", \"got_dawn_revision\": \"f2bc3b3edd6f3a8424b230b17f844f59768d53b8\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"4e9da022508c5f73e027620d449f4196143183cf\", \"got_revision_cp\": \"refs/heads/master@{#828127}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"04515da400d5fbc22d852af1369c4d46bd54991e\", \"got_v8_revision\": \"15247228bbf879eab29da6c7a0b1a608fccbc1fa\", \"got_v8_revision_cp\": \"refs/heads/8.9.4@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e9da022508c5f73e027620d449f4196143183cf\", \"swarm_hashes_refs/heads/master(at){#828127}_without_patch\": {\"absl_hardening_tests\": \"ddc021eb1ab1b8e3067c8ebe8c3e1bf29562813e\", \"accessibility_unittests\": \"4563ffffbd5b36d8213faab111cd91cfbb08f82f\", \"angle_unittests\": \"7dae1d5eabb966447e47379525059b6ae32af03f\", \"app_shell_unittests\": \"f60a5c203c525a6d4068b266e773252ee63f7a68\", \"aura_unittests\": \"5280d257e739c8b13a92fca8f67f001bc1f0165b\", \"base_unittests\": \"76081fbdf4b79e76d481062f6f23a16546a6db3c\", \"base_util_unittests\": \"aa70745eca3c8119bcff18e7c1ccf659c3e8c350\", \"blink_common_unittests\": \"2df6a4d3b60f4791c8b145318321b287ae32142f\", \"blink_fuzzer_unittests\": \"d2f80ded700ab68beac6a101170cdc9c8db6ba45\", \"blink_heap_unittests\": \"6c4c350fe22c572a6e70982cc9d8f454ee4a16ed\", \"blink_platform_unittests\": \"57106a9ebbd8d1b690e7aa7f6248368c02b12736\", \"blink_unittests\": \"a41f5a1976508d2fc6605069d594535701fc61ef\", \"boringssl_crypto_tests\": \"cb1231c21fc042aac5d39a7c9508e82db8502426\", \"boringssl_ssl_tests\": \"d1ab5c076b5ca433b9201f4707a1d6ad8332a046\", \"browser_switcher_bho_unittests\": \"c88d2b1814dec38b29875c732d910c7ac5275e07\", \"browser_tests\": \"9871bd8ab2f8cb9331445b2406c32b45b335e413\", \"capture_unittests\": \"581d8feff99baa38d4baaef138020523a0903673\", \"cast_unittests\": \"0b28ed5f19a152403088063a7cbce7f44c981e00\", \"cc_unittests\": \"249a0660b6ec3b1dfbe7761bcd398d438f8da01d\", \"chrome_app_unittests\": \"ff1e3a542e90a2e79e1f54155d97b0a291f578e0\", \"chrome_cleaner_unittests\": \"c1bbef8eedb3c6f62cae775f6ddbc76a9f65e4e0\", \"chrome_elf_unittests\": \"469656bedc7d55ea81a9c3265f997f45319b4811\", \"chromedriver_unittests\": \"95106da6b53407c373beed702698be261d5a498e\", \"components_browsertests\": \"ff77653a370cd1f7f7674b3fc3187c4d0055af84\", \"components_unittests\": \"00f3bf2912c4a4636033c0c4f82de342e6c4244c\", \"compositor_unittests\": \"9b5e4072f87d362f091903a636752dcfab9bab61\", \"content_browsertests\": \"a389a3cfe32c773d6b9b8ff905c1557e94442b15\", \"content_unittests\": \"992d408509074e6ec4d446b9f79f120e9d26faa8\", \"courgette_unittests\": \"cf7eaa40162b280097609d051a8f804754ff52c6\", \"crashpad_tests\": \"0fb4a63179b48379010a13e9eb5691ac97194ee8\", \"cronet_tests\": \"6e4b6552598d03d3d945ad1c9f4f73a03740485a\", \"cronet_unittests\": \"2c7d1ee03b1f6eb86ca5f42ba806bdec079bcf5a\", \"crypto_unittests\": \"ec88f2844d353fcfe055b7565b28d397d6ad3327\", \"delayloads_unittests\": \"2828431ffd6ce86e58f1047e395c6b757f4c9516\", \"device_unittests\": \"876df7463e39b93e72b8521a49f223dc518a0b59\", \"display_unittests\": \"a53477465770430120f5cd6a054bd0e849e29def\", \"elevation_service_unittests\": \"82c413de250bf11023a6950be2ba31915ac98478\", \"events_unittests\": \"0df74631d53f0d714c475dccfed42980b3b0da1a\", \"extensions_browsertests\": \"11fc3e0fd26f22deb5b6b9e8350b4baf84ca4d34\", \"extensions_unittests\": \"2652287cd493bf5f9ef787c111e6c3bb485963f5\", \"filesystem_service_unittests\": \"48431ce3bedcd73c5664ab5eda3362e26f533106\", \"gcm_unit_tests\": \"37af9ea5494966c182d088d71c644caea5d7aa93\", \"gcp_unittests\": \"b33138ed2ab6162b002277531b8e2cc8a7d47bb8\", \"gfx_unittests\": \"f6597d008ff4fceab4ce0d7ea574ffce35bdb4a2\", \"gin_unittests\": \"765759000350342434ed40a632f6cbe6a5d6480a\", \"google_apis_unittests\": \"9d647bcc55ba9d217fc3c9ffa72168ca418c3db4\", \"gpu_unittests\": \"c43d09cf79435a230366e314806f7cc4e10953b9\", \"gwp_asan_unittests\": \"52455896c29fddadcc8accfcf15e6f23db27a02f\", \"headless_browsertests\": \"4156a89e355aa53326978302abafa170440216b7\", \"headless_unittests\": \"f272994a453efc2c4f47fa8594944f59cb74a698\", \"install_static_unittests\": \"81626381b5c1fba89af148b687cd834c489edbde\", \"installer_util_unittests\": \"916824e0d6922e4843fcdf401e452cf95f16627c\", \"interactive_ui_tests\": \"0183c6770b3195413e02c1a1f561a26abc95a9a3\", \"ipc_tests\": \"a80da608093c1df9f7d7bd1baf06e9973208cdee\", \"jingle_unittests\": \"3c5d59ebb28a5793ce92a45188d937010831b58c\", \"latency_unittests\": \"be17a77a0c5b64f9fed0fc11cb66985ff0475753\", \"libjingle_xmpp_unittests\": \"be86001ce22f5bfb6db3e76a0bff511824888181\", \"liburlpattern_unittests\": \"c72629253b60c04695568931828eaf4a2005d79a\", \"media_blink_unittests\": \"5a133cf6ac70cf351329415e25c6ce444779c370\", \"media_unittests\": \"64ddd80310e0e19dd8f219a573eae6bf1bb88280\", \"message_center_unittests\": \"1bc8816b6597dd05d8d826ea77516cb9c7063ee1\", \"midi_unittests\": \"2d746801604adc7734ce36f7b52f41b450e3d602\", \"mojo_core_unittests\": \"328ecf62eab7cc5e3f832d48f8bfe3b39aa915c5\", \"mojo_unittests\": \"e69614d2335838420ede39ba17f006291f00d36c\", \"nacl_loader_unittests\": \"3b9fb6d4f83283c75e33ebe6fb45508c6c7b585f\", \"native_theme_unittests\": \"2588e21beb3f3f6524a9cf4f40876e7cf37ca6e3\", \"net_unittests\": \"b575b75854d9bef9012afaed36e239218ebf6e3e\", \"notification_helper_unittests\": \"5348e7fd2a5d9081f9f324477a40b0769e6c3b34\", \"pdf_unittests\": \"14312dc1f9396b6d8eabf0208e46e043c8b4cb39\", \"perfetto_unittests\": \"7657d2971dee3e208ce8b1c783572393c9ca0866\", \"ppapi_unittests\": \"e9447f8a8e22f5d6b77fe6b1ab91c710c4dc0089\", \"printing_unittests\": \"db84f315a8e7edd11010d2e4e82d6822342e48ba\", \"remoting_unittests\": \"a7cb1cba782a577ea2f7406f3d632a2a41313709\", \"sbox_integration_tests\": \"23993c0e8fe76eccf55514ad872d98dff3f45bf0\", \"sbox_unittests\": \"c750b8282e179d8d668216f1892c3226076f488b\", \"sbox_validation_tests\": \"811b84f51d8ddd102d5065762791eea2306fb26f\", \"service_manager_unittests\": \"e59c1ff2db4f4c22256236ebaec42a9374022e6a\", \"services_unittests\": \"3f427d206a24e2cbcf96f2fed3b03e9a6294113c\", \"setup_unittests\": \"3d06a7c764ba956ad4a866b0956c8c4b1261ba55\", \"shell_dialogs_unittests\": \"434072c9b48b2bed6825dc708152830c52ebdba2\", \"skia_unittests\": \"01c89cb6e7496c9382bbfe532ef33556634f61bb\", \"snapshot_unittests\": \"77ce65b23e0adacc993ba3759c2fc1fad800ba6a\", \"sql_unittests\": \"b5a5acec4d109e4b08bd1e9ad3cf7fe70414909d\", \"storage_unittests\": \"b3b4b30f031d7979b2f4f246c8e85057083afe6d\", \"sync_integration_tests\": \"2ebd8b49b79478da50268c102eacead3a9ae2710\", \"traffic_annotation_auditor_unittests\": \"5237f5a0e75e810a5a62058c3c8892ef192840e7\", \"ui_base_unittests\": \"c13dc17b54c2fe3615c9546bb69de062b759d399\", \"ui_touch_selection_unittests\": \"d66fea31733096a56c837f6f8494121bccae15ad\", \"unit_tests\": \"9ce202710072fda498f9d48753697192a882dfa3\", \"updater_tests\": \"e53370401027f893a1a02daf0d5b987ada166bcd\", \"url_unittests\": \"fdff5f552723b6b9e6a908c1205a71003ae8cfcd\", \"views_unittests\": \"e24667be299ab4ecb184740c4f75479d15c92114\", \"viz_unittests\": \"cd3615c40ece370ae8554fcaecc7ecbc0c0b4f39\", \"vr_common_unittests\": \"55f3361fe843b042d5717393b009cacabcd1a6ac\", \"vr_pixeltests\": \"7507e594bc8cbfcf767dab6cdeadd826ee123954\", \"weblayer_browsertests\": \"24834f5695871d87d7cbe71e4f16116a9e26151c\", \"weblayer_unittests\": \"ba96dd66968f4f7ccf4a4a41cc6dc18711dc65a2\", \"wm_unittests\": \"0f39ce0d9e748595c59abd634ad9d939a326d1b3\", \"wtf_unittests\": \"928d5f77f8fb556daa5dca80ef85c110410095d9\", \"zlib_unittests\": \"72a9931ec3faf8c41ea04632440b8c15c6d55d60\", \"zucchini_unittests\": \"1603099186c59fa35551d77ac292e18d1659fe0b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-fw95\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5632-2e614e1\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605607399897778",
"status": "COMPLETED",
"status_changed_ts": "1605610834880110",
"tags": [
"build_address:luci.chromium.ci/win-asan/15684",
"builder:win-asan",
"buildset:commit/git/4e9da022508c5f73e027620d449f4196143183cf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e9da022508c5f73e027620d449f4196143183cf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028822711114691920",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863392648940714944/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4fee758eb87de110",
"user_agent:luci-scheduler"
],
"updated_ts": "1605610834885974",
"url": "https://ci.chromium.org/b/8863392648940714944",
"utcnow_ts": "1605610836276597"
}
}
[11:00:36.324] Invocation finished in 1h2m21.092710924s with status SUCCEEDED