[21:01:40.415] New invocation is queued and will start shortly
[21:01:41.515] Starting the invocation (attempt 1)
[21:01:41.559] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:01:41.559] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:01:41.559] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021714408997201904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4514f1953f0c15d8569acb0506d91651d3556c9c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021714408997201904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4514f1953f0c15d8569acb0506d91651d3556c9c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4514f1953f0c15d8569acb0506d91651d3556c9c",
"gitiles_ref:refs/heads/master"
]
}
[21:01:42.459] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612386101633703",
"id": "8856284347051846880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4514f1953f0c15d8569acb0506d91651d3556c9c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612386102097699",
"tags": [
"build_address:luci.chromium.ci/win-asan/17154",
"builder:win-asan",
"buildset:commit/git/4514f1953f0c15d8569acb0506d91651d3556c9c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4514f1953f0c15d8569acb0506d91651d3556c9c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021714408997201904",
"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/8856284347051846880/+/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": "1612386102097817",
"url": "https://ci.chromium.org/b/8856284347051846880",
"utcnow_ts": "1612386102409111"
}
}
[21:01:42.459] Task URL: https://ci.chromium.org/b/8856284347051846880
[21:01:42.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:2:0) after 1m0s
[21:01:46.163] Received PubSub notification, asking Buildbucket for the build status
[21:01:46.259] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:42.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:2:0)
[21:02:42.471] Timer tick, asking Buildbucket for the build status
[21:02:42.665] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:42.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:4:0) after 1m0s
[21:03:42.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:4:0)
[21:03:42.691] Timer tick, asking Buildbucket for the build status
[21:03:43.278] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:43.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:5:0) after 1m0s
[21:04:43.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:5:0)
[21:04:43.307] Timer tick, asking Buildbucket for the build status
[21:04:43.427] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:43.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:6:0) after 1m0s
[21:05:43.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:6:0)
[21:05:43.447] Timer tick, asking Buildbucket for the build status
[21:05:43.561] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:43.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:7:0) after 1m0s
[21:06:43.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:7:0)
[21:06:43.585] Timer tick, asking Buildbucket for the build status
[21:06:43.725] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:43.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:8:0) after 1m0s
[21:07:43.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:8:0)
[21:07:43.741] Timer tick, asking Buildbucket for the build status
[21:07:43.926] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:43.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:9:0) after 1m0s
[21:08:43.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:9:0)
[21:08:43.950] Timer tick, asking Buildbucket for the build status
[21:08:44.014] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:44.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:10:0) after 1m0s
[21:09:44.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:10:0)
[21:09:44.024] Timer tick, asking Buildbucket for the build status
[21:09:44.125] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:44.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:11:0) after 1m0s
[21:10:44.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:11:0)
[21:10:44.152] Timer tick, asking Buildbucket for the build status
[21:10:44.316] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:44.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:12:0) after 1m0s
[21:11:44.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:12:0)
[21:11:44.345] Timer tick, asking Buildbucket for the build status
[21:11:44.421] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:13:0) after 1m0s
[21:12:44.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:13:0)
[21:12:44.597] Timer tick, asking Buildbucket for the build status
[21:12:45.010] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:45.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:14:0) after 1m0s
[21:13:45.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:14:0)
[21:13:45.084] Timer tick, asking Buildbucket for the build status
[21:13:49.635] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:49.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:15:0) after 1m0s
[21:14:49.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:15:0)
[21:14:49.614] Timer tick, asking Buildbucket for the build status
[21:14:49.682] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:49.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:16:0) after 1m0s
[21:15:49.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:16:0)
[21:15:49.660] Timer tick, asking Buildbucket for the build status
[21:15:49.716] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:49.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:17:0) after 1m0s
[21:16:49.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:17:0)
[21:16:49.749] Timer tick, asking Buildbucket for the build status
[21:16:49.852] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:49.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:18:0) after 1m0s
[21:17:49.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:18:0)
[21:17:49.886] Timer tick, asking Buildbucket for the build status
[21:17:50.153] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:50.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:19:0) after 1m0s
[21:18:50.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:19:0)
[21:18:50.240] Timer tick, asking Buildbucket for the build status
[21:18:50.292] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:50.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:20:0) after 1m0s
[21:19:50.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:20:0)
[21:19:50.456] Timer tick, asking Buildbucket for the build status
[21:19:50.606] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:50.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:21:0) after 1m0s
[21:20:50.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:21:0)
[21:20:50.616] Timer tick, asking Buildbucket for the build status
[21:20:50.692] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:50.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:22:0) after 1m0s
[21:21:50.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:22:0)
[21:21:50.772] Timer tick, asking Buildbucket for the build status
[21:21:50.888] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:50.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:23:0) after 1m0s
[21:22:50.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:23:0)
[21:22:50.984] Timer tick, asking Buildbucket for the build status
[21:22:51.917] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:24:0) after 1m0s
[21:23:51.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:24:0)
[21:23:51.988] Timer tick, asking Buildbucket for the build status
[21:23:52.896] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:52.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:25:0) after 1m0s
[21:24:53.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:25:0)
[21:24:53.058] Timer tick, asking Buildbucket for the build status
[21:24:53.345] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:53.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:26:0) after 1m0s
[21:25:53.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:26:0)
[21:25:53.391] Timer tick, asking Buildbucket for the build status
[21:25:53.452] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:53.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:27:0) after 1m0s
[21:26:53.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:27:0)
[21:26:53.486] Timer tick, asking Buildbucket for the build status
[21:26:53.634] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:53.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:28:0) after 1m0s
[21:27:53.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:28:0)
[21:27:53.709] Timer tick, asking Buildbucket for the build status
[21:27:53.764] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:53.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:29:0) after 1m0s
[21:28:53.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:29:0)
[21:28:53.772] Timer tick, asking Buildbucket for the build status
[21:28:53.906] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:53.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:30:0) after 1m0s
[21:29:54.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:30:0)
[21:29:54.050] Timer tick, asking Buildbucket for the build status
[21:29:54.164] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:54.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:31:0) after 1m0s
[21:30:54.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:31:0)
[21:30:54.201] Timer tick, asking Buildbucket for the build status
[21:30:54.374] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:54.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:32:0) after 1m0s
[21:31:54.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:32:0)
[21:31:54.571] Timer tick, asking Buildbucket for the build status
[21:31:54.961] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:54.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:33:0) after 1m0s
[21:32:55.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:33:0)
[21:32:55.094] Timer tick, asking Buildbucket for the build status
[21:32:55.220] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:55.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:34:0) after 1m0s
[21:33:55.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:34:0)
[21:33:55.494] Timer tick, asking Buildbucket for the build status
[21:33:55.610] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:55.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:35:0) after 1m0s
[21:34:55.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:35:0)
[21:34:55.704] Timer tick, asking Buildbucket for the build status
[21:34:56.701] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:56.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:36:0) after 1m0s
[21:35:57.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:36:0)
[21:35:57.226] Timer tick, asking Buildbucket for the build status
[21:35:57.334] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:57.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:37:0) after 1m0s
[21:36:57.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:37:0)
[21:36:57.434] Timer tick, asking Buildbucket for the build status
[21:36:57.577] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:57.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:38:0) after 1m0s
[21:37:57.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:38:0)
[21:37:57.644] Timer tick, asking Buildbucket for the build status
[21:37:57.808] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:57.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:39:0) after 1m0s
[21:38:57.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:39:0)
[21:38:57.858] Timer tick, asking Buildbucket for the build status
[21:38:58.307] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:58.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:40:0) after 1m0s
[21:39:58.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:40:0)
[21:39:58.461] Timer tick, asking Buildbucket for the build status
[21:39:58.661] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:58.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:41:0) after 1m0s
[21:40:58.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:41:0)
[21:40:58.828] Timer tick, asking Buildbucket for the build status
[21:40:58.892] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:58.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:42:0) after 1m0s
[21:41:58.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:42:0)
[21:41:58.928] Timer tick, asking Buildbucket for the build status
[21:41:59.603] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:59.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:43:0) after 1m0s
[21:42:59.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:43:0)
[21:42:59.618] Timer tick, asking Buildbucket for the build status
[21:42:59.680] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:59.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:44:0) after 1m0s
[21:43:59.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:44:0)
[21:43:59.764] Timer tick, asking Buildbucket for the build status
[21:44:00.425] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:00.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:45:0) after 1m0s
[21:45:00.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:45:0)
[21:45:00.714] Timer tick, asking Buildbucket for the build status
[21:45:00.942] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:00.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:46:0) after 1m0s
[21:46:01.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:46:0)
[21:46:01.334] Timer tick, asking Buildbucket for the build status
[21:46:01.452] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:01.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:47:0) after 1m0s
[21:47:01.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:47:0)
[21:47:01.707] Timer tick, asking Buildbucket for the build status
[21:47:01.862] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:01.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:48:0) after 1m0s
[21:48:02.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:48:0)
[21:48:02.229] Timer tick, asking Buildbucket for the build status
[21:48:04.580] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:04.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:49:0) after 1m0s
[21:49:04.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:49:0)
[21:49:04.694] Timer tick, asking Buildbucket for the build status
[21:49:04.832] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:04.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:50:0) after 1m0s
[21:50:04.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:50:0)
[21:50:04.970] Timer tick, asking Buildbucket for the build status
[21:50:05.043] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:05.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:51:0) after 1m0s
[21:51:05.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:51:0)
[21:51:05.528] Timer tick, asking Buildbucket for the build status
[21:51:05.606] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:05.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:52:0) after 1m0s
[21:52:05.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:52:0)
[21:52:05.661] Timer tick, asking Buildbucket for the build status
[21:52:05.946] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:05.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:53:0) after 1m0s
[21:53:06.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:53:0)
[21:53:06.082] Timer tick, asking Buildbucket for the build status
[21:53:06.281] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:06.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:54:0) after 1m0s
[21:54:06.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:54:0)
[21:54:06.403] Timer tick, asking Buildbucket for the build status
[21:54:06.712] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:06.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:55:0) after 1m0s
[21:55:06.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:55:0)
[21:55:06.906] Timer tick, asking Buildbucket for the build status
[21:55:07.054] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:07.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:56:0) after 1m0s
[21:56:07.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:56:0)
[21:56:07.140] Timer tick, asking Buildbucket for the build status
[21:56:07.219] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:07.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:57:0) after 1m0s
[21:57:07.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:57:0)
[21:57:07.401] Timer tick, asking Buildbucket for the build status
[21:57:07.539] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:07.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:58:0) after 1m0s
[21:58:07.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:58:0)
[21:58:07.579] Timer tick, asking Buildbucket for the build status
[21:58:08.954] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:08.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:59:0) after 1m0s
[21:59:09.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:59:0)
[21:59:09.168] Timer tick, asking Buildbucket for the build status
[21:59:09.249] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:09.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:60:0) after 1m0s
[22:00:09.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:60:0)
[22:00:09.404] Timer tick, asking Buildbucket for the build status
[22:00:09.968] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:09.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:61:0) after 1m0s
[22:01:10.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:61:0)
[22:01:10.215] Timer tick, asking Buildbucket for the build status
[22:01:11.136] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:11.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:62:0) after 1m0s
[22:02:11.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:62:0)
[22:02:11.285] Timer tick, asking Buildbucket for the build status
[22:02:11.483] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:11.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:63:0) after 1m0s
[22:03:11.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:63:0)
[22:03:11.575] Timer tick, asking Buildbucket for the build status
[22:03:11.762] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:11.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:64:0) after 1m0s
[22:04:11.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:64:0)
[22:04:11.948] Timer tick, asking Buildbucket for the build status
[22:04:12.187] Build 8856284347051846880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:12.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021714408997201904:65:0) after 1m0s
[22:05:10.408] Received PubSub notification, asking Buildbucket for the build status
[22:05:10.492] Build 8856284347051846880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:05:10.492] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612389908382563",
"created_by": "project:chromium",
"created_ts": "1612386101633703",
"id": "8856284347051846880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4514f1953f0c15d8569acb0506d91651d3556c9c\"}}",
"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\": \"4514f1953f0c15d8569acb0506d91651d3556c9c\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17154, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"2e54a1e7fc49e1c1c659d7be659b832954112106\", \"got_dawn_revision\": \"f2a003d4e94cbb529d638e9a1b8a551c9f3371ab\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"4514f1953f0c15d8569acb0506d91651d3556c9c\", \"got_revision_cp\": \"refs/heads/master@{#850272}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"4514f1953f0c15d8569acb0506d91651d3556c9c\", \"swarm_hashes_refs/heads/master(at){#850272}_without_patch\": {\"absl_hardening_tests\": \"2497a8d05f9292e0243fb4b800ede2b43d4baf66\", \"accessibility_unittests\": \"d25610d54332fd323626c81b08d72b8831cd53e7\", \"angle_unittests\": \"17b4ed540d79f721fa5f0518dcca7bc32f9e5b02\", \"app_shell_unittests\": \"40827431ec52e2f6be9d35e26eaa717d7981a157\", \"aura_unittests\": \"e46d19e4dd63eb45ba8de05effbb51a212e7f77c\", \"base_unittests\": \"abf3c4d8533557d0e0a1750131a054ebeb2f7ef6\", \"base_util_unittests\": \"e0f6ec285e9b395cf52c91a4e19460ee65a2422d\", \"blink_common_unittests\": \"6f94d13d9369789cc0e2eed9984cd26e3d58c5e9\", \"blink_fuzzer_unittests\": \"860eea41eef877f6d229e643cb9f63d127c55aa6\", \"blink_heap_unittests\": \"43279c3a8d8e695e0b83b4610900177c97c9b2b9\", \"blink_platform_unittests\": \"18ea36cf197c8508808077133501f5aa39036688\", \"blink_unittests\": \"ea3c8df0a9eaa0e4cd7b53218e69740af7f95863\", \"boringssl_crypto_tests\": \"1aeb050ffb10744633b4c0b2a54fdb20bf8ac915\", \"boringssl_ssl_tests\": \"e54bca938c8944f5f20db3aa44c53be2bf5b5a5d\", \"browser_switcher_bho_unittests\": \"d5eced3dc46bdc9f104d8c655a751bed176bd309\", \"browser_tests\": \"f3e8ec5d3e30e09f2e694d2a31e33f43bfecda3a\", \"capture_unittests\": \"50a4ead6d782fe76887af1034300d2d741efaf5f\", \"cast_unittests\": \"009fd723c8f6d8d0cddc5dd245a956aca8cea95e\", \"cc_unittests\": \"b7af52570662abb5e76acf9fd2d1194fb1e7b7e0\", \"chrome_app_unittests\": \"f4d4f86347e0287721ecca39a89f4705e28225fd\", \"chrome_cleaner_unittests\": \"88bb56edcbace3dd087b2a2282d6d38a66ce07aa\", \"chrome_elf_unittests\": \"075d1badb105965bb9a1c371b2515a7d9cd9f295\", \"chromedriver_unittests\": \"266515275b1e9d19c793542642e0c204bbfdcd84\", \"components_browsertests\": \"a35734171eaaefc733836717da050870dbd0e3a4\", \"components_unittests\": \"6cbeca8107aecb805228a64f2ec029ac3f76887c\", \"compositor_unittests\": \"f6e8d3b5636dc77e14f797e60f5751c0816499f9\", \"content_browsertests\": \"073e1002f90d3d5883d9340100ede65384c0e77b\", \"content_unittests\": \"8126d89cf6eb25f342fa8e4b51b3cad9b2734fc0\", \"courgette_unittests\": \"c909258cd0e43c6cadfa9a12031afe4a0ae23636\", \"crashpad_tests\": \"2be595490929493b8a250137ded4addeeb018b85\", \"cronet_tests\": \"99742f4b02b24011d4c81f208063dc5ce1e7b6b3\", \"cronet_unittests\": \"e1626d28624f71343645aa8a4c46b3dfbd6e1d6c\", \"crypto_unittests\": \"62250b6642d979d944f9add4562fe538ca3db1b3\", \"delayloads_unittests\": \"ba6dd5c41d1b5877a1572185cedc5e6ae937f9de\", \"device_unittests\": \"ab2f0640f1b6b79a74a3ca47f2e1b5656da4c98b\", \"display_unittests\": \"4935fdfb0270e7c4a44c839cccd6c86c4400876b\", \"elevation_service_unittests\": \"7d22724a2149edc1a9358543ba4ccac9eb302474\", \"events_unittests\": \"461479a30d5b1ac814d868767fe057df946ed461\", \"extensions_browsertests\": \"b02585aa274d48ef6b4bdc458cd6c77d56e6c9a1\", \"extensions_unittests\": \"ffca3ad86c331ddef935fb6206e556d2d15a7d46\", \"filesystem_service_unittests\": \"df4ee8215b1c94dd71c77a59797e5ec998858cb2\", \"gcm_unit_tests\": \"e5b31326c55de57bdbbbbb8eda0791903a6e0187\", \"gcp_unittests\": \"ccbdea7e211cc13f33b48e48bc0866e8d99e7e4f\", \"gfx_unittests\": \"daea82d8d9b7e4dc58487ae8fee9753c27450bb2\", \"gin_unittests\": \"84f9052158461af157a9305f08bcbf20d2a82b38\", \"google_apis_unittests\": \"863fc1ce9b630bc4e496e671dd7928d2a873ef52\", \"gpu_unittests\": \"50ae06cbac1e795e5178799466a622058d118a30\", \"gwp_asan_unittests\": \"7cb3ecaa4f9af2fbbd61d0f2552ff5931ce6c616\", \"headless_browsertests\": \"99b3e25207ec4097424ac37ac960a43513abea66\", \"headless_unittests\": \"26111c395acb5ab62348c60d38b5689f3129c27b\", \"install_static_unittests\": \"90c47e529f1796ec4c4814b6dfd7f92c76e72998\", \"installer_util_unittests\": \"8c48ae433141f9929db219e09341cd4ef7de7c67\", \"interactive_ui_tests\": \"8d1c61f8b7973ad1a233eeb0926d1e1840d4a5e3\", \"ipc_tests\": \"2e39fa3f987c6d8000d7aedb8c944b276da9b51c\", \"jingle_unittests\": \"b90945c5d655624e40d212cfaaf9033bc83275a4\", \"latency_unittests\": \"4540fcd9c16ef544cec86c98f8d545980416fa9b\", \"libjingle_xmpp_unittests\": \"2667199dde719f6f2475161a41659c28e697b783\", \"liburlpattern_unittests\": \"f8b8be299521167f20be1c47d105f9002fe83dbc\", \"media_blink_unittests\": \"0475d04a7a484d9e9708cd34fb83d6f3f6ef9c50\", \"media_unittests\": \"eb8fffabcffca2f7d60bd635c4f3c2f73718df4c\", \"message_center_unittests\": \"8f9d14e3b5a209f3f881ef83fd5534ea48189ffd\", \"midi_unittests\": \"2a24fd8a1b479c60233a5f8e718e0954280915bb\", \"mojo_core_unittests\": \"ea0c30b9e48baa3f7039616c92ebfe65f437ebe9\", \"mojo_unittests\": \"2ca09d4b8f7ef2a9d739d32a4f4086e9dabc9da3\", \"nacl_loader_unittests\": \"5c94c4278f41b965dfa02cf2c78459f6ccd18fbb\", \"native_theme_unittests\": \"d717d1af3c9ae9edd37e9fed967aca5d20ed76a5\", \"net_unittests\": \"81c4fcb1cdea0d0722595374d7552640059e41de\", \"notification_helper_unittests\": \"bf461c22a33026faa627b73134127294514e8e7b\", \"pdf_unittests\": \"f7eef1bc86763a7529c3c803603799bd97d3d850\", \"perfetto_unittests\": \"b78fc5abcb3862c28269c83d7fb3a9d3350e2449\", \"ppapi_unittests\": \"55accd3fd41963e7e0ef8391fa7b7ca63981de7b\", \"printing_unittests\": \"67def34b3108b206c5669ced9efa946b1a83553e\", \"remoting_unittests\": \"2835a88be86022c248667cc7346d5441dcb57ee6\", \"sbox_integration_tests\": \"e34d0ef0d1cc43f80b8ffc5bc312f8dd24499d07\", \"sbox_unittests\": \"3b7c699a7d479d7d2f66ca8058f64aaee8cc9eff\", \"sbox_validation_tests\": \"360be88b115a3e04aee66663d81713cabf700f16\", \"service_manager_unittests\": \"6e40d015f4a573a74a4033389b6958363e7d4e8a\", \"services_unittests\": \"68d53811135c2da4dc416c514001fd1d3ba0ea89\", \"setup_unittests\": \"90d648c46361c83d7346c9c1a19a87fff488c468\", \"shell_dialogs_unittests\": \"9786307af2256ac491c6d430faf0e9c39914e1fa\", \"skia_unittests\": \"8e1de5087181fbcf05ed25287b7c38cf1755496f\", \"snapshot_unittests\": \"0c44ff93893793ce0aea5161568c7d698ab3dcc0\", \"sql_unittests\": \"f118c861bd39ded532b35a4d3674a68304da602b\", \"storage_unittests\": \"4740920539040bce801b5c721cba9e80ce27e3fa\", \"sync_integration_tests\": \"ad80bd0e9fc61703b5f7717d528612897b19cf70\", \"traffic_annotation_auditor_unittests\": \"cb4e4bb992a30c9a7e5e44f4d741cd2210ff57a7\", \"ui_base_unittests\": \"0eec29e89afd7dcccf081ece2357642db94a436d\", \"ui_touch_selection_unittests\": \"ece294fccc2dce7e0503aef20c93a56e2253ecc5\", \"unit_tests\": \"4ce50a12054b44a084a1a56e965f37d952780a21\", \"updater_tests\": \"e8ae9ecacf1229d3b3c7326031f3517f75207c54\", \"url_unittests\": \"1c7d60ad02dbc055b56a3c2a742034eedf078bfd\", \"views_unittests\": \"eb78212be11785030e5df11d5788c1c720bfcea3\", \"viz_unittests\": \"d5c8b9b11f747a7448484b0237b9daea450481d5\", \"vr_common_unittests\": \"d26676f0af5da2c46e0271425828d7ff1ec3b17f\", \"vr_pixeltests\": \"297cd181e928d6cac1cb852f7071a117dcf064ac\", \"weblayer_browsertests\": \"776377f05255b3c2a645d85e16ee3cfb7f608833\", \"weblayer_unittests\": \"6a358568058317454629a412d5d8292738d7b0bf\", \"wm_unittests\": \"41ad412c8682be587614b107e314fa907631c5df\", \"wtf_unittests\": \"fb0f178b37cf42d23aaa9b394262ae5c06de344a\", \"zlib_unittests\": \"71b8266b3950f601264e1f561181ea5b6873c76c\", \"zucchini_unittests\": \"3da245871d1ad172457d0c9520286ede14b1ad09\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"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-0-be5b\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5676-c76598c\"], \"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": "1612386104804020",
"status": "COMPLETED",
"status_changed_ts": "1612389909104626",
"tags": [
"build_address:luci.chromium.ci/win-asan/17154",
"builder:win-asan",
"buildset:commit/git/4514f1953f0c15d8569acb0506d91651d3556c9c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4514f1953f0c15d8569acb0506d91651d3556c9c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021714408997201904",
"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/8856284347051846880/+/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:518284f6caea9310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612389909143275",
"url": "https://ci.chromium.org/b/8856284347051846880",
"utcnow_ts": "1612389910482380"
}
}
[22:05:10.492] Invocation finished in 1h3m30.097126979s with status SUCCEEDED