[21:43:29.511] New invocation is queued and will start shortly
[21:43:30.713] Starting the invocation (attempt 1)
[21:43:30.752] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:43:30.753] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:43:30.753] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026785208133388944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"253c0e09295f9b79e276126cd071d3e1ae744701\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026785208133388944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/253c0e09295f9b79e276126cd071d3e1ae744701",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/253c0e09295f9b79e276126cd071d3e1ae744701",
"gitiles_ref:refs/heads/master"
]
}
[21:43:32.219] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607550210854642",
"id": "8861355146062288992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"253c0e09295f9b79e276126cd071d3e1ae744701\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607550212074569",
"tags": [
"build_address:luci.chromium.ci/win-asan/16126",
"builder:win-asan",
"buildset:commit/git/253c0e09295f9b79e276126cd071d3e1ae744701",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/253c0e09295f9b79e276126cd071d3e1ae744701",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026785208133388944",
"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/8861355146062288992/+/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": "1607550212074692",
"url": "https://ci.chromium.org/b/8861355146062288992",
"utcnow_ts": "1607550212209944"
}
}
[21:43:32.219] Task URL: https://ci.chromium.org/b/8861355146062288992
[21:43:32.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:2:0) after 1m0s
[21:43:35.156] Received PubSub notification, asking Buildbucket for the build status
[21:43:35.293] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:32.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:2:0)
[21:44:32.219] Timer tick, asking Buildbucket for the build status
[21:44:32.293] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:32.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:4:0) after 1m0s
[21:45:32.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:4:0)
[21:45:32.343] Timer tick, asking Buildbucket for the build status
[21:45:32.606] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:32.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:5:0) after 1m0s
[21:46:32.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:5:0)
[21:46:32.586] Timer tick, asking Buildbucket for the build status
[21:46:32.769] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:32.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:6:0) after 1m0s
[21:47:32.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:6:0)
[21:47:32.840] Timer tick, asking Buildbucket for the build status
[21:47:32.958] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:32.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:7:0) after 1m0s
[21:48:33.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:7:0)
[21:48:33.092] Timer tick, asking Buildbucket for the build status
[21:48:33.213] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:33.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:8:0) after 1m0s
[21:49:33.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:8:0)
[21:49:33.493] Timer tick, asking Buildbucket for the build status
[21:49:33.711] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:33.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:9:0) after 1m0s
[21:50:33.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:9:0)
[21:50:33.892] Timer tick, asking Buildbucket for the build status
[21:50:33.971] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:33.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:10:0) after 1m0s
[21:51:34.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:10:0)
[21:51:34.121] Timer tick, asking Buildbucket for the build status
[21:51:34.182] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:34.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:11:0) after 1m0s
[21:52:34.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:11:0)
[21:52:34.288] Timer tick, asking Buildbucket for the build status
[21:52:34.429] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:34.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:12:0) after 1m0s
[21:53:34.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:12:0)
[21:53:34.450] Timer tick, asking Buildbucket for the build status
[21:53:34.568] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:34.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:13:0) after 1m0s
[21:54:34.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:13:0)
[21:54:34.553] Timer tick, asking Buildbucket for the build status
[21:54:34.620] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:34.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:14:0) after 1m0s
[21:55:34.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:14:0)
[21:55:34.707] Timer tick, asking Buildbucket for the build status
[21:55:34.773] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:34.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:15:0) after 1m0s
[21:56:34.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:15:0)
[21:56:34.797] Timer tick, asking Buildbucket for the build status
[21:56:34.909] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:34.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:16:0) after 1m0s
[21:57:34.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:16:0)
[21:57:34.969] Timer tick, asking Buildbucket for the build status
[21:57:35.110] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:35.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:17:0) after 1m0s
[21:58:35.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:17:0)
[21:58:35.125] Timer tick, asking Buildbucket for the build status
[21:58:36.157] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:36.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:18:0) after 1m0s
[21:59:36.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:18:0)
[21:59:36.195] Timer tick, asking Buildbucket for the build status
[21:59:36.312] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:36.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:19:0) after 1m0s
[22:00:36.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:19:0)
[22:00:36.335] Timer tick, asking Buildbucket for the build status
[22:00:36.457] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:36.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:20:0) after 1m0s
[22:01:36.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:20:0)
[22:01:36.495] Timer tick, asking Buildbucket for the build status
[22:01:36.698] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:36.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:21:0) after 1m0s
[22:02:36.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:21:0)
[22:02:36.714] Timer tick, asking Buildbucket for the build status
[22:02:36.834] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:36.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:22:0) after 1m0s
[22:03:36.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:22:0)
[22:03:36.864] Timer tick, asking Buildbucket for the build status
[22:03:36.984] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:36.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:23:0) after 1m0s
[22:04:37.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:23:0)
[22:04:37.021] Timer tick, asking Buildbucket for the build status
[22:04:37.156] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:37.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:24:0) after 1m0s
[22:05:37.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:24:0)
[22:05:37.178] Timer tick, asking Buildbucket for the build status
[22:05:37.346] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:37.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:25:0) after 1m0s
[22:06:37.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:25:0)
[22:06:37.444] Timer tick, asking Buildbucket for the build status
[22:06:37.656] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:37.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:26:0) after 1m0s
[22:07:37.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:26:0)
[22:07:37.718] Timer tick, asking Buildbucket for the build status
[22:07:37.844] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:37.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:27:0) after 1m0s
[22:08:37.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:27:0)
[22:08:37.905] Timer tick, asking Buildbucket for the build status
[22:08:38.023] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:38.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:28:0) after 1m0s
[22:09:38.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:28:0)
[22:09:38.044] Timer tick, asking Buildbucket for the build status
[22:09:38.223] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:38.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:29:0) after 1m0s
[22:10:38.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:29:0)
[22:10:38.303] Timer tick, asking Buildbucket for the build status
[22:10:38.481] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:38.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:30:0) after 1m0s
[22:11:38.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:30:0)
[22:11:38.535] Timer tick, asking Buildbucket for the build status
[22:11:38.657] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:38.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:31:0) after 1m0s
[22:12:38.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:31:0)
[22:12:38.694] Timer tick, asking Buildbucket for the build status
[22:12:38.780] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:38.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:32:0) after 1m0s
[22:13:38.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:32:0)
[22:13:38.824] Timer tick, asking Buildbucket for the build status
[22:13:38.988] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:38.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:33:0) after 1m0s
[22:14:39.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:33:0)
[22:14:39.010] Timer tick, asking Buildbucket for the build status
[22:14:39.540] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:39.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:34:0) after 1m0s
[22:15:39.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:34:0)
[22:15:39.639] Timer tick, asking Buildbucket for the build status
[22:15:39.772] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:39.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:35:0) after 1m0s
[22:16:39.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:35:0)
[22:16:39.924] Timer tick, asking Buildbucket for the build status
[22:16:40.414] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:40.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:36:0) after 1m0s
[22:17:40.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:36:0)
[22:17:40.427] Timer tick, asking Buildbucket for the build status
[22:17:41.152] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:41.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:37:0) after 1m0s
[22:18:41.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:37:0)
[22:18:41.312] Timer tick, asking Buildbucket for the build status
[22:18:41.441] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:41.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:38:0) after 1m0s
[22:19:41.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:38:0)
[22:19:41.489] Timer tick, asking Buildbucket for the build status
[22:19:41.589] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:41.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:39:0) after 1m0s
[22:20:41.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:39:0)
[22:20:41.714] Timer tick, asking Buildbucket for the build status
[22:20:41.860] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:41.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:40:0) after 1m0s
[22:21:42.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:40:0)
[22:21:42.122] Timer tick, asking Buildbucket for the build status
[22:21:42.208] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:42.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:41:0) after 1m0s
[22:22:43.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:41:0)
[22:22:43.912] Timer tick, asking Buildbucket for the build status
[22:22:44.021] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:44.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:42:0) after 1m0s
[22:23:45.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:42:0)
[22:23:45.586] Timer tick, asking Buildbucket for the build status
[22:23:45.923] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:45.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:43:0) after 1m0s
[22:24:45.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:43:0)
[22:24:45.929] Timer tick, asking Buildbucket for the build status
[22:24:46.002] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:46.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:44:0) after 1m0s
[22:25:46.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:44:0)
[22:25:46.049] Timer tick, asking Buildbucket for the build status
[22:25:46.178] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:46.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:45:0) after 1m0s
[22:26:46.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:45:0)
[22:26:46.314] Timer tick, asking Buildbucket for the build status
[22:26:46.446] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:46.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:46:0) after 1m0s
[22:27:46.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:46:0)
[22:27:46.526] Timer tick, asking Buildbucket for the build status
[22:27:46.640] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:46.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:47:0) after 1m0s
[22:28:46.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:47:0)
[22:28:46.663] Timer tick, asking Buildbucket for the build status
[22:28:46.801] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:46.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:48:0) after 1m0s
[22:29:46.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:48:0)
[22:29:46.951] Timer tick, asking Buildbucket for the build status
[22:29:47.053] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:47.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:49:0) after 1m0s
[22:30:47.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:49:0)
[22:30:47.159] Timer tick, asking Buildbucket for the build status
[22:30:47.265] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:47.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:50:0) after 1m0s
[22:31:47.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:50:0)
[22:31:47.587] Timer tick, asking Buildbucket for the build status
[22:31:48.066] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:48.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:51:0) after 1m0s
[22:32:48.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:51:0)
[22:32:48.198] Timer tick, asking Buildbucket for the build status
[22:32:48.440] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:48.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:52:0) after 1m0s
[22:33:48.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:52:0)
[22:33:48.594] Timer tick, asking Buildbucket for the build status
[22:33:48.726] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:48.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:53:0) after 1m0s
[22:34:48.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:53:0)
[22:34:48.897] Timer tick, asking Buildbucket for the build status
[22:34:48.985] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:48.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:54:0) after 1m0s
[22:35:49.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:54:0)
[22:35:49.142] Timer tick, asking Buildbucket for the build status
[22:35:49.306] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:49.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:55:0) after 1m0s
[22:36:49.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:55:0)
[22:36:49.449] Timer tick, asking Buildbucket for the build status
[22:36:50.111] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:50.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:56:0) after 1m0s
[22:37:50.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:56:0)
[22:37:50.237] Timer tick, asking Buildbucket for the build status
[22:37:50.320] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:50.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:57:0) after 1m0s
[22:38:50.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:57:0)
[22:38:50.572] Timer tick, asking Buildbucket for the build status
[22:38:50.785] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:50.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:58:0) after 1m0s
[22:39:50.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:58:0)
[22:39:50.764] Timer tick, asking Buildbucket for the build status
[22:39:50.835] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:50.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:59:0) after 1m0s
[22:40:50.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:59:0)
[22:40:50.876] Timer tick, asking Buildbucket for the build status
[22:40:51.180] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:51.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:60:0) after 1m0s
[22:41:51.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:60:0)
[22:41:51.254] Timer tick, asking Buildbucket for the build status
[22:41:51.395] Build 8861355146062288992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:51.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026785208133388944:61:0) after 1m0s
[22:42:29.397] Received PubSub notification, asking Buildbucket for the build status
[22:42:29.477] Build 8861355146062288992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:42:29.477] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607553748381957",
"created_by": "project:chromium",
"created_ts": "1607550210854642",
"id": "8861355146062288992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"253c0e09295f9b79e276126cd071d3e1ae744701\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"253c0e09295f9b79e276126cd071d3e1ae744701\", \"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-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16126, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"378653f844d404993507bbc81245ff674c0dc8c6\", \"got_dawn_revision\": \"99d6c14a3b34a370760caea4d408e0d6c194ed56\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"253c0e09295f9b79e276126cd071d3e1ae744701\", \"got_revision_cp\": \"refs/heads/master@{#835361}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8837f2e7f4f03e648834f158da717829a8af27bb\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"253c0e09295f9b79e276126cd071d3e1ae744701\", \"swarm_hashes_refs/heads/master(at){#835361}_without_patch\": {\"absl_hardening_tests\": \"a73d14d08b75279fda17d8e8b10131a2198b94e7\", \"accessibility_unittests\": \"cb97fbedc17d72ebec9d8d27420d3cf1ed97dcb5\", \"angle_unittests\": \"55a5a1a26315f0f9464862d51110ddb55b63200d\", \"app_shell_unittests\": \"da87f1e6386ca49aa1d2412499b8df6329658e92\", \"aura_unittests\": \"782f4c4e3e9f0dc4f840ca38f9816f572443e7e1\", \"base_unittests\": \"7b1032aa1ada713ceed173639b6cb309180d8ef7\", \"base_util_unittests\": \"7e2ea8b3a2e84a9eaeb1ed36c1871e400a2843a5\", \"blink_common_unittests\": \"aead2cfeb9d5d8d25c44e36b4343a8c92a7238ab\", \"blink_fuzzer_unittests\": \"8391304e431c4e1b85d0c38ef45d95b2ee8faf35\", \"blink_heap_unittests\": \"c264007fee6c11ab7d27af120b285078a9366ce1\", \"blink_platform_unittests\": \"ab46a44b4e0f4fc87f30ff2649f9aafa385c601b\", \"blink_unittests\": \"64221589509dd8a1b8517de8610b6245a218e10a\", \"boringssl_crypto_tests\": \"677f177b93058b25b7f53cc1c69d9f5fba1c5e8a\", \"boringssl_ssl_tests\": \"a150ea59e94b77fde0d2e46adad955a8f6fe568b\", \"browser_switcher_bho_unittests\": \"f2cdf50059d60fc61fcb8cd0c5068f93b464158e\", \"browser_tests\": \"6a5a4cf0e2bfb90118b665538d36707e9048d8e1\", \"capture_unittests\": \"96217696d81ba1c9ae8d1ad44af227b0baeb4b2f\", \"cast_unittests\": \"bec652261495dcf980a46698b94dc14c011ba93b\", \"cc_unittests\": \"e427729833ce7115329c39449e0f5f80e95c8078\", \"chrome_app_unittests\": \"8fb0e9d3c3fd7a2b92b5c7774e73eb7bfaccc662\", \"chrome_cleaner_unittests\": \"879c35b85049e9b7ac50b7cd9c44464b78c0f9df\", \"chrome_elf_unittests\": \"e8686e650ad35dc0a74ed29ad63c9404e7617466\", \"chromedriver_unittests\": \"27e0f94813457fd68001efdbb3f85397a497df96\", \"components_browsertests\": \"47bb4d7df1e23d3ce086b3e246e8e268dc30e648\", \"components_unittests\": \"c2e92b17dd7ce91b9962095ed477d009dec324d2\", \"compositor_unittests\": \"048d1ac97c0dcba0d87160dd0944b02f2ca65b2c\", \"content_browsertests\": \"a76389cf3b08fb02958c818a3de3ce2a48ea4529\", \"content_unittests\": \"119eb476ca61df4fd68ba81e5b8afcc587d512fc\", \"courgette_unittests\": \"eadbfb2e2b2e862b669d58806724582956b8c2c0\", \"crashpad_tests\": \"284b554cf8cf970e69dcfb65c2df625ce4343382\", \"cronet_tests\": \"d5feae0cb7ad4e65e5d493c7117612486f4405b6\", \"cronet_unittests\": \"5f18a10a4318faaf98945fbd0162e3dd3b77c2e1\", \"crypto_unittests\": \"31427e0ec42779e99a17cfc259b456ba886f16a4\", \"delayloads_unittests\": \"614673833b5317bed20f50db0fd99480561b7c47\", \"device_unittests\": \"ca5779cfe83bf912f2cacb67e499f780dce26dae\", \"display_unittests\": \"890ac418719384f432cc2557cc490be18518e75d\", \"elevation_service_unittests\": \"a666cb2beac4535372c8b0f3dfe7c58dfe63c291\", \"events_unittests\": \"2bb23c7751f810ef6ae30387916d6792f8809c1e\", \"extensions_browsertests\": \"ead4f7cea2d4235d3dba95b032bf69272e092f8f\", \"extensions_unittests\": \"3b87d72b68253117c5b50dc9640325e4dd89a39c\", \"filesystem_service_unittests\": \"1dd787378e672a5d3491269d0ea5a387dc0937ea\", \"gcm_unit_tests\": \"9b2797d1511c5ae73ab9b510028c3c0514e91138\", \"gcp_unittests\": \"74ef3f9ce11573e83fcf002b246c6abe19dd7d00\", \"gfx_unittests\": \"aba8af88c067394b3fc068136bc1c18613d73ad4\", \"gin_unittests\": \"076748655e58a9bc1741198a8209a6fa77377558\", \"google_apis_unittests\": \"33fb60df1ee69e1452c40a7c575254b66e686992\", \"gpu_unittests\": \"49707ee21efb24a95b9d712e8f70661a9b0d1527\", \"gwp_asan_unittests\": \"6edc96cf1c5e0cea11b056c59d89a4535bee11a0\", \"headless_browsertests\": \"f6eed41f063ba61a09b897fefcd71fc34d55d06d\", \"headless_unittests\": \"82c5a8015998ddc770444ce1991c34c2e362b710\", \"install_static_unittests\": \"5c8453c4f4933e5830d6f7b15dddfce82497e6d2\", \"installer_util_unittests\": \"4261d242c0f4d281aeb4c6bccfa1e1409724b16b\", \"interactive_ui_tests\": \"54b46fd3fc82e65b7c2a6303f5a79135515ab63f\", \"ipc_tests\": \"dbcc5149b33c68169e03adc5d5977def2efd6c14\", \"jingle_unittests\": \"e5fa6c46bf5376fd04a12eeb50f1f2e0cb77c399\", \"latency_unittests\": \"01e827720aec006fed2746b39d9a3173cb6be274\", \"libjingle_xmpp_unittests\": \"2ca1d461642fe0aa97c0ac6c16f6006ef2645344\", \"liburlpattern_unittests\": \"bcdbb20e2f67136c96f3f1446a6af78d050fdac7\", \"media_blink_unittests\": \"77adc0a920ca7f830fa1e769ea83a9cc21d6a652\", \"media_unittests\": \"6ed578968e7336c63536c36ecec56705ab92d96b\", \"message_center_unittests\": \"04c37c5c52652fa6f14fcc971dcc3f5d47fa4fa9\", \"midi_unittests\": \"849b11c6dd951737eef7cfddc6eb56045afd0f3f\", \"mojo_core_unittests\": \"c65031f4a6b605c6a20f0276d864298f5cdd7382\", \"mojo_unittests\": \"b32da99ebdaeb93e119940dc7bfe2cc1b9cc1e20\", \"nacl_loader_unittests\": \"cdeea8b8ae83b93ee75c742d454d2cb657121bd6\", \"native_theme_unittests\": \"c91a960feb712fdf51ff1ee7f8ff53f2f283824f\", \"net_unittests\": \"3b363674fbef72ea702e3e3fea6a5111c00a9996\", \"notification_helper_unittests\": \"16d64f4221d2254dce329a79423f48fc5181d280\", \"pdf_unittests\": \"08cd375c1e42d6aa4d3b3144efe06e0d738c125a\", \"perfetto_unittests\": \"c5ca2a3f059fe685ef3481d13f940fcaea00a93d\", \"ppapi_unittests\": \"60671dbcf59fb6f1751af3b46597f14c3a5d803c\", \"printing_unittests\": \"9f981742996761dbdd0ecfa16ba0d5bf5ca2cc0b\", \"remoting_unittests\": \"53e0e7c5f3fe018facff790888110198d71655f7\", \"sbox_integration_tests\": \"d2f836df19545472b9ea0b16a731740e6720baa7\", \"sbox_unittests\": \"69bb5fc72eee4fd96016eb287204a189a5214f14\", \"sbox_validation_tests\": \"846f4496dc557b8e6018b1cc0ba5b91268f7f7bf\", \"service_manager_unittests\": \"4f07ce401d9b83ea2e8b9d1322afe4889e21da03\", \"services_unittests\": \"bf94769f730bbbd358ae3ae93876a45d3b2cee66\", \"setup_unittests\": \"193913fe99549917bb9a45f0c9794ab7252ad6ef\", \"shell_dialogs_unittests\": \"242b79ce053fe4ebd26f2b92fcffe65c6283cfc1\", \"skia_unittests\": \"ea0dbdd6c633559a08bee55a0af6495df7ca532a\", \"snapshot_unittests\": \"d387480ed6dbac1c75daa6781a3433517281fefd\", \"sql_unittests\": \"8e01699bc5c5301cb81b9e9dc2126ec6a497d778\", \"storage_unittests\": \"7c6ae27cfcc835ff605509841689054b99b64f15\", \"sync_integration_tests\": \"11042b6070675f05dc1b8a6d9fe1a26be8a1b871\", \"traffic_annotation_auditor_unittests\": \"c1b07c1c1634aa2d227f229d084aee850129adeb\", \"ui_base_unittests\": \"0ebbfa98ca92b268eae3b7ae42222314827b081c\", \"ui_touch_selection_unittests\": \"6e0034f5ab314d636aed71d5a9e66ee1a7af5211\", \"unit_tests\": \"2d6e8f7d8104bbc51aa84b819cf697d7f924476f\", \"updater_tests\": \"66e9345579fa028e877488603d9429cfdda37cd5\", \"url_unittests\": \"238d7313fafea0ec33ff79ae5a3584c950926d37\", \"views_unittests\": \"c11c7c410b6b7bd15570fb7b7e77e7d20e161d93\", \"viz_unittests\": \"8c043f5bf4d92a86b207ceccd73c266d99f22e86\", \"vr_common_unittests\": \"789ec22afb4b655755972bf10cc6d02f554bde96\", \"vr_pixeltests\": \"5f4fe824e30e0582743bfa3440797c6d8128b611\", \"weblayer_browsertests\": \"4307a3641e13ba2bd0354e6d601751ba1f52e6ad\", \"weblayer_unittests\": \"80be9db5ccdd5e992ee5d06cfce7cff81067b296\", \"wm_unittests\": \"eaf07db042751ad222a96e227af0c0ec9b81bca5\", \"wtf_unittests\": \"844a41d040044b43df734e632052d170cbbcba06\", \"zlib_unittests\": \"5be74b1c8ec62715096702b34b93eac5d57942dd\", \"zucchini_unittests\": \"eeecd5908802dd8d61ef64a2159a4a0cc1e27651\"}}, \"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-6-3vmi\"], \"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\": [\"5640-d0eb544\"], \"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": "1607550214490393",
"status": "COMPLETED",
"status_changed_ts": "1607553748640300",
"tags": [
"build_address:luci.chromium.ci/win-asan/16126",
"builder:win-asan",
"buildset:commit/git/253c0e09295f9b79e276126cd071d3e1ae744701",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/253c0e09295f9b79e276126cd071d3e1ae744701",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026785208133388944",
"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/8861355146062288992/+/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:50624721b578fb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607553748644496",
"url": "https://ci.chromium.org/b/8861355146062288992",
"utcnow_ts": "1607553749465624"
}
}
[22:42:29.477] Invocation finished in 58m59.982646569s with status SUCCEEDED