[14:19:37.334] New invocation is queued and will start shortly
[14:19:38.458] Starting the invocation (attempt 1)
[14:19:38.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:19:38.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:19:38.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021830300843049680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021830300843049680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"gitiles_ref:refs/heads/master"
]
}
[14:19:39.231] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612275578622545",
"id": "8856400238828813600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612275579095239",
"tags": [
"build_address:luci.chromium.ci/win-asan/17131",
"builder:win-asan",
"buildset:commit/git/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021830300843049680",
"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/8856400238828813600/+/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": "1612275579095528",
"url": "https://ci.chromium.org/b/8856400238828813600",
"utcnow_ts": "1612275579220579"
}
}
[14:19:39.231] Task URL: https://ci.chromium.org/b/8856400238828813600
[14:19:39.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:2:0) after 1m0s
[14:19:42.628] Received PubSub notification, asking Buildbucket for the build status
[14:19:42.826] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:39.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:2:0)
[14:20:39.323] Timer tick, asking Buildbucket for the build status
[14:20:39.394] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:39.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:4:0) after 1m0s
[14:21:39.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:4:0)
[14:21:39.435] Timer tick, asking Buildbucket for the build status
[14:21:39.536] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:39.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:5:0) after 1m0s
[14:22:39.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:5:0)
[14:22:39.549] Timer tick, asking Buildbucket for the build status
[14:22:39.646] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:39.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:6:0) after 1m0s
[14:23:39.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:6:0)
[14:23:39.690] Timer tick, asking Buildbucket for the build status
[14:23:39.844] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:39.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:7:0) after 1m0s
[14:24:39.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:7:0)
[14:24:39.858] Timer tick, asking Buildbucket for the build status
[14:24:39.968] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:39.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:8:0) after 1m0s
[14:25:40.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:8:0)
[14:25:40.035] Timer tick, asking Buildbucket for the build status
[14:25:40.170] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:40.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:9:0) after 1m0s
[14:26:40.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:9:0)
[14:26:40.287] Timer tick, asking Buildbucket for the build status
[14:26:40.359] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:40.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:10:0) after 1m0s
[14:27:40.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:10:0)
[14:27:40.413] Timer tick, asking Buildbucket for the build status
[14:27:40.484] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:40.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:11:0) after 1m0s
[14:28:40.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:11:0)
[14:28:40.575] Timer tick, asking Buildbucket for the build status
[14:28:40.659] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:40.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:12:0) after 1m0s
[14:29:40.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:12:0)
[14:29:40.830] Timer tick, asking Buildbucket for the build status
[14:29:40.958] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:40.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:13:0) after 1m0s
[14:30:41.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:13:0)
[14:30:41.011] Timer tick, asking Buildbucket for the build status
[14:30:41.255] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:41.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:14:0) after 1m0s
[14:31:41.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:14:0)
[14:31:41.262] Timer tick, asking Buildbucket for the build status
[14:31:41.332] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:41.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:15:0) after 1m0s
[14:32:41.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:15:0)
[14:32:41.386] Timer tick, asking Buildbucket for the build status
[14:32:41.521] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:41.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:16:0) after 1m0s
[14:33:41.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:16:0)
[14:33:41.600] Timer tick, asking Buildbucket for the build status
[14:33:41.678] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:41.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:17:0) after 1m0s
[14:34:41.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:17:0)
[14:34:41.721] Timer tick, asking Buildbucket for the build status
[14:34:41.849] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:41.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:18:0) after 1m0s
[14:35:41.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:18:0)
[14:35:41.889] Timer tick, asking Buildbucket for the build status
[14:35:42.024] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:19:0) after 1m0s
[14:36:42.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:19:0)
[14:36:42.088] Timer tick, asking Buildbucket for the build status
[14:36:42.148] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:42.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:20:0) after 1m0s
[14:37:42.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:20:0)
[14:37:42.185] Timer tick, asking Buildbucket for the build status
[14:37:42.252] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:42.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:21:0) after 1m0s
[14:38:42.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:21:0)
[14:38:42.371] Timer tick, asking Buildbucket for the build status
[14:38:42.795] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:42.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:22:0) after 1m0s
[14:39:42.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:22:0)
[14:39:42.875] Timer tick, asking Buildbucket for the build status
[14:39:42.975] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:42.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:23:0) after 1m0s
[14:40:43.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:23:0)
[14:40:43.061] Timer tick, asking Buildbucket for the build status
[14:40:43.240] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:24:0) after 1m0s
[14:41:43.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:24:0)
[14:41:43.318] Timer tick, asking Buildbucket for the build status
[14:41:43.451] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:43.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:25:0) after 1m0s
[14:42:43.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:25:0)
[14:42:43.496] Timer tick, asking Buildbucket for the build status
[14:42:43.632] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:43.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:26:0) after 1m0s
[14:43:43.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:26:0)
[14:43:43.655] Timer tick, asking Buildbucket for the build status
[14:43:43.735] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:43.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:27:0) after 1m0s
[14:44:43.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:27:0)
[14:44:43.848] Timer tick, asking Buildbucket for the build status
[14:44:44.147] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:44.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:28:0) after 1m0s
[14:45:44.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:28:0)
[14:45:44.201] Timer tick, asking Buildbucket for the build status
[14:45:44.351] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:44.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:29:0) after 1m0s
[14:46:44.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:29:0)
[14:46:44.414] Timer tick, asking Buildbucket for the build status
[14:46:44.703] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:30:0) after 1m0s
[14:47:44.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:30:0)
[14:47:44.683] Timer tick, asking Buildbucket for the build status
[14:47:44.830] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:44.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:31:0) after 1m0s
[14:48:44.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:31:0)
[14:48:44.917] Timer tick, asking Buildbucket for the build status
[14:48:45.459] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:45.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:32:0) after 1m0s
[14:49:45.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:32:0)
[14:49:45.578] Timer tick, asking Buildbucket for the build status
[14:49:45.927] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:45.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:33:0) after 1m0s
[14:50:46.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:33:0)
[14:50:46.036] Timer tick, asking Buildbucket for the build status
[14:50:46.240] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:46.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:34:0) after 1m0s
[14:51:46.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:34:0)
[14:51:46.328] Timer tick, asking Buildbucket for the build status
[14:51:46.526] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:46.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:35:0) after 1m0s
[14:52:46.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:35:0)
[14:52:46.598] Timer tick, asking Buildbucket for the build status
[14:52:46.929] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:46.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:36:0) after 1m0s
[14:53:47.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:36:0)
[14:53:47.014] Timer tick, asking Buildbucket for the build status
[14:53:47.232] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:47.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:37:0) after 1m0s
[14:54:47.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:37:0)
[14:54:47.351] Timer tick, asking Buildbucket for the build status
[14:54:47.822] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:47.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:38:0) after 1m0s
[14:55:47.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:38:0)
[14:55:47.977] Timer tick, asking Buildbucket for the build status
[14:55:48.312] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:48.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:39:0) after 1m0s
[14:56:48.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:39:0)
[14:56:48.333] Timer tick, asking Buildbucket for the build status
[14:56:48.603] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:48.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:40:0) after 1m0s
[14:57:48.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:40:0)
[14:57:48.722] Timer tick, asking Buildbucket for the build status
[14:57:48.960] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:41:0) after 1m0s
[14:58:49.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:41:0)
[14:58:49.008] Timer tick, asking Buildbucket for the build status
[14:58:49.346] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:49.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:42:0) after 1m0s
[14:59:49.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:42:0)
[14:59:49.382] Timer tick, asking Buildbucket for the build status
[14:59:50.508] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:50.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:43:0) after 1m0s
[15:00:50.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:43:0)
[15:00:50.589] Timer tick, asking Buildbucket for the build status
[15:00:50.698] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:50.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:44:0) after 1m0s
[15:01:50.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:44:0)
[15:01:50.884] Timer tick, asking Buildbucket for the build status
[15:01:51.235] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:51.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:45:0) after 1m0s
[15:02:51.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:45:0)
[15:02:51.355] Timer tick, asking Buildbucket for the build status
[15:02:52.312] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:52.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:46:0) after 1m0s
[15:03:52.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:46:0)
[15:03:52.372] Timer tick, asking Buildbucket for the build status
[15:03:52.610] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:52.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:47:0) after 1m0s
[15:04:52.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:47:0)
[15:04:52.661] Timer tick, asking Buildbucket for the build status
[15:04:52.848] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:52.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:48:0) after 1m0s
[15:05:52.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:48:0)
[15:05:52.899] Timer tick, asking Buildbucket for the build status
[15:05:53.115] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:53.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:49:0) after 1m0s
[15:06:53.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:49:0)
[15:06:53.288] Timer tick, asking Buildbucket for the build status
[15:06:53.353] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:53.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:50:0) after 1m0s
[15:07:53.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:50:0)
[15:07:53.417] Timer tick, asking Buildbucket for the build status
[15:07:53.705] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:53.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:51:0) after 1m0s
[15:08:53.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:51:0)
[15:08:53.809] Timer tick, asking Buildbucket for the build status
[15:08:53.997] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:53.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:52:0) after 1m0s
[15:09:54.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:52:0)
[15:09:54.125] Timer tick, asking Buildbucket for the build status
[15:09:54.424] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:54.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:53:0) after 1m0s
[15:10:54.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:53:0)
[15:10:54.627] Timer tick, asking Buildbucket for the build status
[15:10:54.929] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:54.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:54:0) after 1m0s
[15:11:55.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:54:0)
[15:11:55.185] Timer tick, asking Buildbucket for the build status
[15:11:55.520] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:55.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:55:0) after 1m0s
[15:12:55.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:55:0)
[15:12:55.585] Timer tick, asking Buildbucket for the build status
[15:12:55.741] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:55.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:56:0) after 1m0s
[15:13:55.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:56:0)
[15:13:55.903] Timer tick, asking Buildbucket for the build status
[15:13:56.613] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:56.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:57:0) after 1m0s
[15:14:56.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:57:0)
[15:14:56.782] Timer tick, asking Buildbucket for the build status
[15:14:57.051] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:57.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:58:0) after 1m0s
[15:15:57.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:58:0)
[15:15:57.159] Timer tick, asking Buildbucket for the build status
[15:15:57.316] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:57.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:59:0) after 1m0s
[15:16:57.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:59:0)
[15:16:57.538] Timer tick, asking Buildbucket for the build status
[15:16:57.910] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:57.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:60:0) after 1m0s
[15:17:58.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:60:0)
[15:17:58.123] Timer tick, asking Buildbucket for the build status
[15:17:58.389] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:58.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:61:0) after 1m0s
[15:18:58.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:61:0)
[15:18:58.460] Timer tick, asking Buildbucket for the build status
[15:18:59.119] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:59.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:62:0) after 1m0s
[15:19:59.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:62:0)
[15:19:59.202] Timer tick, asking Buildbucket for the build status
[15:19:59.579] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:59.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:63:0) after 1m0s
[15:20:59.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:63:0)
[15:20:59.620] Timer tick, asking Buildbucket for the build status
[15:20:59.939] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:59.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:64:0) after 1m0s
[15:21:59.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:64:0)
[15:21:59.968] Timer tick, asking Buildbucket for the build status
[15:22:00.099] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:00.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:65:0) after 1m0s
[15:23:00.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:65:0)
[15:23:00.131] Timer tick, asking Buildbucket for the build status
[15:23:00.309] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:00.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:66:0) after 1m0s
[15:24:00.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:66:0)
[15:24:00.395] Timer tick, asking Buildbucket for the build status
[15:24:00.542] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:00.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:67:0) after 1m0s
[15:25:00.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:67:0)
[15:25:00.897] Timer tick, asking Buildbucket for the build status
[15:25:02.179] Build 8856400238828813600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:02.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021830300843049680:68:0) after 1m0s
[15:25:54.662] Received PubSub notification, asking Buildbucket for the build status
[15:25:54.807] Build 8856400238828813600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:25:54.808] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612279553264441",
"created_by": "project:chromium",
"created_ts": "1612275578622545",
"id": "8856400238828813600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\"}}",
"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\": \"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\", \"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-2-30ob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17131, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"43e39e1e8e387644f22d87c7a846e57172bd1312\", \"got_dawn_revision\": \"199ba447c511fcdc9f06595f85d42bb97f519417\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\", \"got_revision_cp\": \"refs/heads/master@{#849578}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"084201251b83b335e314810dda69e21838233623\", \"got_v8_revision_cp\": \"refs/heads/9.0.110@{#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\": \"2d3868be2cfed222ee6ae5a0f0c3b84438155bd0\", \"swarm_hashes_refs/heads/master(at){#849578}_without_patch\": {\"absl_hardening_tests\": \"1726408473e9e22f85bc6812521f41950f255d8d\", \"accessibility_unittests\": \"8427fdf8ab799541b4167ba71fcb99c0271edf82\", \"angle_unittests\": \"ea23e7f781a6b80b99a72a10dba77abbd6b4ffd8\", \"app_shell_unittests\": \"d07234de0c8e053b0226d13e056fd35b885cf41a\", \"aura_unittests\": \"48c5c5a561f1ef2f1dde2ecd1ec195eea0201c1e\", \"base_unittests\": \"7f4f2668dc7121d3c522b6ee67b0781b17bd935a\", \"base_util_unittests\": \"d911f30504db7f149eec9de9565facfcfd7ad9fc\", \"blink_common_unittests\": \"b329507817316767da5b3805671a1898ed77b672\", \"blink_fuzzer_unittests\": \"af5a4cae7f11b1d5b83cf186c50663e9f3e415b8\", \"blink_heap_unittests\": \"739b1f9b9d6a2751b586ea58d2684dc94c3af106\", \"blink_platform_unittests\": \"e1069364d2b38bfac0ddc481b79fd2095d7bd4a4\", \"blink_unittests\": \"7477e13ab730b76f72c4861863fcfeaaa0e5d7c1\", \"boringssl_crypto_tests\": \"edf2e10614e4d7fbabb6e2b4e56a2630204c35a1\", \"boringssl_ssl_tests\": \"f061c32389acaf2905500266add5eb654ecd8ea7\", \"browser_switcher_bho_unittests\": \"88ab1df89fcd45ad1435f45abbc651a56ffdf7a8\", \"browser_tests\": \"b03230a8d6ca29d256749b729a00fee48be07a25\", \"capture_unittests\": \"1b7b26b5285849c2afc99998e8df3450aacd2faf\", \"cast_unittests\": \"a963f4f3d724df2b681c23883d60cfff9f08afc0\", \"cc_unittests\": \"3ea7108499d2b82fc06d3a2fd7b4cc779d0b2a5c\", \"chrome_app_unittests\": \"2387982f6f43908d401261ca3be93fed3c8d17f9\", \"chrome_cleaner_unittests\": \"df09035a5bb23c806aa611f4571e54904d52d1e1\", \"chrome_elf_unittests\": \"85f53fb955b005f2df5aa4c559a2ed7d4d920232\", \"chromedriver_unittests\": \"901186f0e2eea4e4316e8e9214e5edc291fe1e73\", \"components_browsertests\": \"7c4da3a94ae395559f59f7e88a1fa4e04e02b86d\", \"components_unittests\": \"5b609ec3e454b1bc0a576873d676256fabf20982\", \"compositor_unittests\": \"849e7f179c0f9e5d23153c97efc56606067a48cc\", \"content_browsertests\": \"23c73cd918c0dd565d827033793a181b97ef8539\", \"content_unittests\": \"8961c90611a8757dd6893c594650fdc5d4f3719e\", \"courgette_unittests\": \"9560636e129ef54e040b686330bc90577209603c\", \"crashpad_tests\": \"b66e6e1b90acab1eba13d89e7e792d7d638931f1\", \"cronet_tests\": \"9641c5f2e780ea71250ecdc98d95c220c202a5a5\", \"cronet_unittests\": \"7adb1876b5681a4d22caef8a43f2baddde4adc11\", \"crypto_unittests\": \"be3d8d8ae68e229a11699aa2ce6ffff67d5a2891\", \"delayloads_unittests\": \"c9b7e1ce4b0f112b23443bdc394677619e174dea\", \"device_unittests\": \"56c5ca51c0d1b2865b1b98c894d6a7fa92fabfde\", \"display_unittests\": \"886b1e39394c022b313cdec7247e372cb45b1cdc\", \"elevation_service_unittests\": \"2c34dc954834619bf8f665c133a567ea13b6b08b\", \"events_unittests\": \"cdae4814ebc9937b431382a34d4fe0b3a8adb3c3\", \"extensions_browsertests\": \"7cca263e4219c181950a11d87ea8cb6e59baee55\", \"extensions_unittests\": \"1b8f20204924c18f4433fc3abd4dc73f05cb5247\", \"filesystem_service_unittests\": \"180fea9c2ab961e538acb24dd742fcb637dc521f\", \"gcm_unit_tests\": \"164ace70eaafc6f415a013ae5c5d6342ca1d6657\", \"gcp_unittests\": \"292bddc93ed048c74a06b58a35471ed71459bc83\", \"gfx_unittests\": \"ac51e34177b698a550ee18c210457935567066db\", \"gin_unittests\": \"7c1cbb6a89bc50ca3a769410910b819698d8e52d\", \"google_apis_unittests\": \"7cf6d37858293d6f531ec0d316c332eafce68dbd\", \"gpu_unittests\": \"dc64c97c3cd1da5438cf51143b731c9dc21a9d06\", \"gwp_asan_unittests\": \"274019a9a50d17638738f5f56172318033a1766e\", \"headless_browsertests\": \"36c14b30f163217cf245e7efa7f1b5d6efd453af\", \"headless_unittests\": \"cc2386bb1df3ee61de827871060e05d1e0b391bd\", \"install_static_unittests\": \"de4f61419988d00ee482297f926de2016af7cac1\", \"installer_util_unittests\": \"e95b8c06ff0f4386478e1ec6c354fc9805acdafd\", \"interactive_ui_tests\": \"52e20d7760c70b63023553355cf8e659705503e2\", \"ipc_tests\": \"19c57755c8afe6f27ffa55472e9c8cd5169964fd\", \"jingle_unittests\": \"52f8a14218783c2668ac440fb7b04803678c0a86\", \"latency_unittests\": \"28ddf32c96d1422aa1c319b1d7c761599faa8f8d\", \"libjingle_xmpp_unittests\": \"fb81887492bc2124d09936f957193c29d24a0e47\", \"liburlpattern_unittests\": \"8a6519df76d7b835e16a0bb23c4b6aecb08fdf2c\", \"media_blink_unittests\": \"e05febab52566a37fd83324a99c8c977ebf0376c\", \"media_unittests\": \"c16b7c68315193cc2eaba2626533c9e632dadd78\", \"message_center_unittests\": \"0500eeae82cc90a64bf9eb65ea7c97755ae22d57\", \"midi_unittests\": \"36269e39f3a2cbc5964b543dd3198a256b678f99\", \"mojo_core_unittests\": \"e981b3f2ed86ee1e0a459978c20571a19920bd68\", \"mojo_unittests\": \"d03ef24fe347cb4e8624741c00002e4698108a87\", \"nacl_loader_unittests\": \"57df166ef161e33495a17a22d860b1d06028922d\", \"native_theme_unittests\": \"2130c85252fdd66664100479719b1df313f2f46a\", \"net_unittests\": \"a953ccb7254aa97952ca7a1b8d6d8bb9e743b71e\", \"notification_helper_unittests\": \"881da39908268a64d2c20d68a3ddfaa0d6c705cb\", \"pdf_unittests\": \"70656d670577a84f0f312394bfef40e1bebaecb7\", \"perfetto_unittests\": \"8625b7542f05d72eaf2638b55a5d8e03b9758226\", \"ppapi_unittests\": \"1206e67681b0de438b0116bb20e6cee368315cbf\", \"printing_unittests\": \"6f52312ab19cafaa9682c037808f09e3ea686701\", \"remoting_unittests\": \"1d8590491744fdddd7d257beef865ab6a9ab49d8\", \"sbox_integration_tests\": \"1387f1465985b475470085e07317dedcc755ff28\", \"sbox_unittests\": \"0a42b578c4fa4815e44f943630a5b76b94a53141\", \"sbox_validation_tests\": \"03106d5ea08dfff43ceab6bd769ae52e28ac65a9\", \"service_manager_unittests\": \"771d64be6c4ab6a11d218d9514d4d6383690792f\", \"services_unittests\": \"851c1298715f1c89c612bcc411fb11c1ef804a54\", \"setup_unittests\": \"70c776ffa4e6be9f12c392660213e0d4ee283477\", \"shell_dialogs_unittests\": \"58b0eb680f353a8989d7aa27b1fe133fcc4f084f\", \"skia_unittests\": \"39fb853a5ebbf2c06978067a831193b6fa95393e\", \"snapshot_unittests\": \"49d0e8f955d0a3420b6de887899a6a4cce88bb6e\", \"sql_unittests\": \"f681d2cd85d2e0543860113ad252300499a2a9ef\", \"storage_unittests\": \"126a13b43f25b56e1bacd5b2ef73b99e996833a6\", \"sync_integration_tests\": \"a914247c87b70dd67cc6459e4db48278dff537ff\", \"traffic_annotation_auditor_unittests\": \"cf12724f76b0661d542c03cbb7b15d391c2d70aa\", \"ui_base_unittests\": \"23c9d9dcdf982147d76d2f2434090d29dc12e056\", \"ui_touch_selection_unittests\": \"cd934a8fc27eba934b805dc0258b57ff6a578021\", \"unit_tests\": \"5631698a5f1c747be08336c9c7fb6d8f4f656365\", \"updater_tests\": \"7099f796da25fa8bf3a02c423d7911124f632f1b\", \"url_unittests\": \"e8f33a5551472cf3b8fd69209c5a77c316906e01\", \"views_unittests\": \"a018aa0bc9e409c228268a566eaf69b3aab39207\", \"viz_unittests\": \"39012499ae707b77ae1bfeb2b6776a2b2677abd8\", \"vr_common_unittests\": \"153c7f076d6f0a780ce642a423800829cbfadf8a\", \"vr_pixeltests\": \"031fc7cf6eda4936d7a8ecbc621691752dc5287e\", \"weblayer_browsertests\": \"9167531044d7d6d08da046d18a7963370bec9ab2\", \"weblayer_unittests\": \"2783f36a156ddca6ef8a2de411890d29958e7c37\", \"wm_unittests\": \"02b765bf3f58b3cedf3dd6762587b37ee3271adc\", \"wtf_unittests\": \"1de9fc20b5319d4a326007c36f359189ed0bb729\", \"zlib_unittests\": \"9345de151e6e1e5571f055e4fb7eec6965f80741\", \"zucchini_unittests\": \"d571109f4423f275add2933062286100dfb7b720\"}}, \"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-2-30ob\"], \"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\": [\"5673-2413400\"], \"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": "1612275580795817",
"status": "COMPLETED",
"status_changed_ts": "1612279553818238",
"tags": [
"build_address:luci.chromium.ci/win-asan/17131",
"builder:win-asan",
"buildset:commit/git/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d3868be2cfed222ee6ae5a0f0c3b84438155bd0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021830300843049680",
"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/8856400238828813600/+/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:517bee81a2398a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612279553823392",
"url": "https://ci.chromium.org/b/8856400238828813600",
"utcnow_ts": "1612279554789415"
}
}
[15:25:54.808] Invocation finished in 1h6m17.490324516s with status SUCCEEDED