[17:09:11.240] New invocation is queued and will start shortly
[17:09:12.376] Starting the invocation (attempt 1)
[17:09:12.465] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:09:12.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:09:12.469] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028161420379210480",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e9d21717491f34a0b5d85f177ccc32d6efaec623\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028161420379210480",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"gitiles_ref:refs/heads/master"
]
}
[17:09:13.066] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606237752561450",
"id": "8862731358328388880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e9d21717491f34a0b5d85f177ccc32d6efaec623\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606237752951667",
"tags": [
"build_address:luci.chromium.ci/win-asan/15825",
"builder:win-asan",
"buildset:commit/git/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028161420379210480",
"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/8862731358328388880/+/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": "1606237752951920",
"url": "https://ci.chromium.org/b/8862731358328388880",
"utcnow_ts": "1606237753051774"
}
}
[17:09:13.066] Task URL: https://ci.chromium.org/b/8862731358328388880
[17:09:13.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:2:0) after 1m0s
[17:09:22.526] Received PubSub notification, asking Buildbucket for the build status
[17:09:22.599] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:13.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:2:0)
[17:10:13.279] Timer tick, asking Buildbucket for the build status
[17:10:13.418] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:13.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:4:0) after 1m0s
[17:11:13.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:4:0)
[17:11:13.451] Timer tick, asking Buildbucket for the build status
[17:11:13.868] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:13.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:5:0) after 1m0s
[17:12:13.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:5:0)
[17:12:13.902] Timer tick, asking Buildbucket for the build status
[17:12:14.012] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:14.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:6:0) after 1m0s
[17:13:14.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:6:0)
[17:13:14.033] Timer tick, asking Buildbucket for the build status
[17:13:14.239] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:7:0) after 1m0s
[17:14:14.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:7:0)
[17:14:14.317] Timer tick, asking Buildbucket for the build status
[17:14:14.470] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:14.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:8:0) after 1m0s
[17:15:14.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:8:0)
[17:15:14.485] Timer tick, asking Buildbucket for the build status
[17:15:14.632] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:14.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:9:0) after 1m0s
[17:16:14.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:9:0)
[17:16:14.634] Timer tick, asking Buildbucket for the build status
[17:16:14.696] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:14.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:10:0) after 1m0s
[17:17:14.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:10:0)
[17:17:14.738] Timer tick, asking Buildbucket for the build status
[17:17:14.800] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:14.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:11:0) after 1m0s
[17:18:14.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:11:0)
[17:18:14.887] Timer tick, asking Buildbucket for the build status
[17:18:14.961] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:14.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:12:0) after 1m0s
[17:19:15.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:12:0)
[17:19:15.009] Timer tick, asking Buildbucket for the build status
[17:19:15.080] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:15.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:13:0) after 1m0s
[17:20:15.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:13:0)
[17:20:15.180] Timer tick, asking Buildbucket for the build status
[17:20:15.298] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:15.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:14:0) after 1m0s
[17:21:15.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:14:0)
[17:21:15.427] Timer tick, asking Buildbucket for the build status
[17:21:16.267] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:15:0) after 1m0s
[17:22:16.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:15:0)
[17:22:16.311] Timer tick, asking Buildbucket for the build status
[17:22:16.410] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:16.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:16:0) after 1m0s
[17:23:16.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:16:0)
[17:23:16.471] Timer tick, asking Buildbucket for the build status
[17:23:16.538] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:16.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:17:0) after 1m0s
[17:24:16.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:17:0)
[17:24:16.612] Timer tick, asking Buildbucket for the build status
[17:24:16.668] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:16.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:18:0) after 1m0s
[17:25:16.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:18:0)
[17:25:16.727] Timer tick, asking Buildbucket for the build status
[17:25:16.857] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:16.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:19:0) after 1m0s
[17:26:16.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:19:0)
[17:26:16.866] Timer tick, asking Buildbucket for the build status
[17:26:17.007] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:20:0) after 1m0s
[17:27:17.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:20:0)
[17:27:17.034] Timer tick, asking Buildbucket for the build status
[17:27:17.095] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:17.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:21:0) after 1m0s
[17:28:17.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:21:0)
[17:28:17.210] Timer tick, asking Buildbucket for the build status
[17:28:17.356] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:17.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:22:0) after 1m0s
[17:29:17.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:22:0)
[17:29:17.465] Timer tick, asking Buildbucket for the build status
[17:29:17.762] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:17.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:23:0) after 1m0s
[17:30:17.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:23:0)
[17:30:17.779] Timer tick, asking Buildbucket for the build status
[17:30:17.952] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:17.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:24:0) after 1m0s
[17:31:18.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:24:0)
[17:31:18.023] Timer tick, asking Buildbucket for the build status
[17:31:18.223] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:18.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:25:0) after 1m0s
[17:32:18.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:25:0)
[17:32:18.247] Timer tick, asking Buildbucket for the build status
[17:32:18.353] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:18.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:26:0) after 1m0s
[17:33:18.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:26:0)
[17:33:18.384] Timer tick, asking Buildbucket for the build status
[17:33:18.930] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:18.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:27:0) after 1m0s
[17:34:18.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:27:0)
[17:34:18.965] Timer tick, asking Buildbucket for the build status
[17:34:19.088] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:19.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:28:0) after 1m0s
[17:35:19.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:28:0)
[17:35:19.075] Timer tick, asking Buildbucket for the build status
[17:35:19.718] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:19.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:29:0) after 1m0s
[17:36:19.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:29:0)
[17:36:19.752] Timer tick, asking Buildbucket for the build status
[17:36:19.944] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:19.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:30:0) after 1m0s
[17:37:19.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:30:0)
[17:37:19.961] Timer tick, asking Buildbucket for the build status
[17:37:20.082] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:20.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:31:0) after 1m0s
[17:38:20.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:31:0)
[17:38:20.170] Timer tick, asking Buildbucket for the build status
[17:38:20.307] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:20.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:32:0) after 1m0s
[17:39:20.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:32:0)
[17:39:20.421] Timer tick, asking Buildbucket for the build status
[17:39:20.608] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:20.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:33:0) after 1m0s
[17:40:20.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:33:0)
[17:40:20.755] Timer tick, asking Buildbucket for the build status
[17:40:21.133] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:21.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:34:0) after 1m0s
[17:41:21.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:34:0)
[17:41:21.194] Timer tick, asking Buildbucket for the build status
[17:41:21.390] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:21.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:35:0) after 1m0s
[17:42:21.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:35:0)
[17:42:21.436] Timer tick, asking Buildbucket for the build status
[17:42:21.533] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:36:0) after 1m0s
[17:43:21.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:36:0)
[17:43:21.602] Timer tick, asking Buildbucket for the build status
[17:43:21.998] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:21.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:37:0) after 1m0s
[17:44:22.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:37:0)
[17:44:22.048] Timer tick, asking Buildbucket for the build status
[17:44:22.119] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:22.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:38:0) after 1m0s
[17:45:22.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:38:0)
[17:45:22.152] Timer tick, asking Buildbucket for the build status
[17:45:22.288] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:22.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:39:0) after 1m0s
[17:46:22.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:39:0)
[17:46:22.289] Timer tick, asking Buildbucket for the build status
[17:46:22.449] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:22.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:40:0) after 1m0s
[17:47:22.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:40:0)
[17:47:22.444] Timer tick, asking Buildbucket for the build status
[17:47:22.511] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:22.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:41:0) after 1m0s
[17:48:22.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:41:0)
[17:48:22.563] Timer tick, asking Buildbucket for the build status
[17:48:22.806] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:22.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:42:0) after 1m0s
[17:49:22.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:42:0)
[17:49:22.855] Timer tick, asking Buildbucket for the build status
[17:49:23.085] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:23.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:43:0) after 1m0s
[17:50:23.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:43:0)
[17:50:23.145] Timer tick, asking Buildbucket for the build status
[17:50:23.845] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:23.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:44:0) after 1m0s
[17:51:23.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:44:0)
[17:51:23.869] Timer tick, asking Buildbucket for the build status
[17:51:24.194] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:24.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:45:0) after 1m0s
[17:52:24.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:45:0)
[17:52:24.224] Timer tick, asking Buildbucket for the build status
[17:52:24.324] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:24.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:46:0) after 1m0s
[17:53:24.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:46:0)
[17:53:24.313] Timer tick, asking Buildbucket for the build status
[17:53:24.382] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:24.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:47:0) after 1m0s
[17:54:24.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:47:0)
[17:54:24.428] Timer tick, asking Buildbucket for the build status
[17:54:24.590] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:24.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:48:0) after 1m0s
[17:55:24.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:48:0)
[17:55:24.593] Timer tick, asking Buildbucket for the build status
[17:55:24.688] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:24.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:49:0) after 1m0s
[17:56:24.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:49:0)
[17:56:24.721] Timer tick, asking Buildbucket for the build status
[17:56:24.868] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:24.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:50:0) after 1m0s
[17:57:24.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:50:0)
[17:57:24.877] Timer tick, asking Buildbucket for the build status
[17:57:25.026] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:25.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:51:0) after 1m0s
[17:58:25.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:51:0)
[17:58:25.084] Timer tick, asking Buildbucket for the build status
[17:58:25.180] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:25.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:52:0) after 1m0s
[17:59:25.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:52:0)
[17:59:25.327] Timer tick, asking Buildbucket for the build status
[17:59:25.467] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:25.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:53:0) after 1m0s
[18:00:25.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:53:0)
[18:00:25.511] Timer tick, asking Buildbucket for the build status
[18:00:25.737] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:25.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:54:0) after 1m0s
[18:01:25.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:54:0)
[18:01:25.875] Timer tick, asking Buildbucket for the build status
[18:01:25.988] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:25.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:55:0) after 1m0s
[18:02:26.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:55:0)
[18:02:26.037] Timer tick, asking Buildbucket for the build status
[18:02:26.171] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:26.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:56:0) after 1m0s
[18:03:26.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:56:0)
[18:03:26.219] Timer tick, asking Buildbucket for the build status
[18:03:26.306] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:26.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:57:0) after 1m0s
[18:04:26.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:57:0)
[18:04:26.455] Timer tick, asking Buildbucket for the build status
[18:04:26.600] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:26.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:58:0) after 1m0s
[18:05:26.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:58:0)
[18:05:26.667] Timer tick, asking Buildbucket for the build status
[18:05:26.854] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:26.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:59:0) after 1m0s
[18:06:26.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:59:0)
[18:06:26.903] Timer tick, asking Buildbucket for the build status
[18:06:26.999] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:26.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:60:0) after 1m0s
[18:07:27.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:60:0)
[18:07:27.057] Timer tick, asking Buildbucket for the build status
[18:07:27.205] Build 8862731358328388880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:27.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028161420379210480:61:0) after 1m0s
[18:08:04.892] Received PubSub notification, asking Buildbucket for the build status
[18:08:05.148] Build 8862731358328388880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:08:05.148] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606241283869683",
"created_by": "project:chromium",
"created_ts": "1606237752561450",
"id": "8862731358328388880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e9d21717491f34a0b5d85f177ccc32d6efaec623\"}}",
"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\": \"e9d21717491f34a0b5d85f177ccc32d6efaec623\", \"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-2gr3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15825, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"084b862ba31bd6134158aa8c5c4fb86d926982a5\", \"got_dawn_revision\": \"03badec5d73ab4138f798b94221546c496177a09\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"e9d21717491f34a0b5d85f177ccc32d6efaec623\", \"got_revision_cp\": \"refs/heads/master@{#830604}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"4ff956a829fefd66af0deeb9bb091fda4389fa21\", \"got_v8_revision_cp\": \"refs/heads/8.9.39@{#1}\", \"got_webrtc_revision\": \"3f77eb468fc4a9a7f5e2751d3d3cbbda38e95082\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32683}\", \"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\": \"e9d21717491f34a0b5d85f177ccc32d6efaec623\", \"swarm_hashes_refs/heads/master(at){#830604}_without_patch\": {\"absl_hardening_tests\": \"2db5a7a47522ba1f3b1cf2a5604bd49c0f8eccb7\", \"accessibility_unittests\": \"b2bf0480f33d58b801f0c1ad255a5929a5e67ca8\", \"angle_unittests\": \"94b24ca92bd23a7f5fcdadfa63c1dae36c817a61\", \"app_shell_unittests\": \"043ca1dd27c63ba992cba901d6041a0e3ddf6887\", \"aura_unittests\": \"7555d8a78b38178b87dd8b1346fab71098114ba0\", \"base_unittests\": \"5b16149922fff8b981cf81f25c038e4bc609a8cb\", \"base_util_unittests\": \"e52c94fba98d9d37ed410f883ae630279020eb39\", \"blink_common_unittests\": \"b03eff9c47929f3c179d4e58b121ca55e7ead043\", \"blink_fuzzer_unittests\": \"f102d37c6719819a8c73e3e5cb6b1e3c17cb5be8\", \"blink_heap_unittests\": \"8a943d78d3bba60673fdf0b12c5a724f69244b94\", \"blink_platform_unittests\": \"789888d3a1bdbdb38c2a51d26ceb0a75808ce04c\", \"blink_unittests\": \"7d35d4d514a74a45fa870e4f108eff80ecf82029\", \"boringssl_crypto_tests\": \"a785cb429bb132a117ccfda812cb4d9f6da8023f\", \"boringssl_ssl_tests\": \"3f6e2d34aad6193c545b7ec7fac3e96daa343ead\", \"browser_switcher_bho_unittests\": \"3034421574e05ad5f758f0ae2ba3df6a91693234\", \"browser_tests\": \"400642eb12ac4318afa91f414b4d3e2c5d79cff1\", \"capture_unittests\": \"89c54371a9dc0a90888894563b7f1a52d2494895\", \"cast_unittests\": \"d0777f97db002071041712d23f3d91f4deb1c5d5\", \"cc_unittests\": \"bff76bdf88252a38e4969450fdc479e001e87ac1\", \"chrome_app_unittests\": \"116e985048f78182ddb898be70b3bb562a5cfb74\", \"chrome_cleaner_unittests\": \"cfd0f6e573aca835aa496caefa84010860aa043d\", \"chrome_elf_unittests\": \"a2e61ed463e1cf63084afa7a2a5cd05e8adb7e94\", \"chromedriver_unittests\": \"1ff32489c2fbaef044a16ecb5a4ff9ae43b4cfb4\", \"components_browsertests\": \"7bb89490976882403e2abe0b38ed44a5e8e6e8ef\", \"components_unittests\": \"06f2e1c4734fe615e5558518f4f285be7cf06eae\", \"compositor_unittests\": \"b94abe20ea430beb33198a80f4b98afbd28875cb\", \"content_browsertests\": \"797cb74ad1b042a3f13e4db930c7187275a8200d\", \"content_unittests\": \"9b711bfd68574e16e7094455b8ad9b2a5b0f1cb3\", \"courgette_unittests\": \"60edd7d60964251c540f4ee28ae8bdbf2819c5f7\", \"crashpad_tests\": \"7781ca7e9a156b0533bca0c5c1f0a631fa9ae99b\", \"cronet_tests\": \"d6708c55a9d444fc8bd4ea69abf82d67c0237f2f\", \"cronet_unittests\": \"dccbfa9ecf0ce7290c6b87c12527925315f2c6b6\", \"crypto_unittests\": \"458a8c83f65377acf7fad688a57a42035feed0aa\", \"delayloads_unittests\": \"3f8ef2e6b291d0134bae954a236e025681f56c44\", \"device_unittests\": \"dbfe91d328670c3eea07cbc8cfe3df7ed5e1ca31\", \"display_unittests\": \"28401387ea0d00d9d1c31786f250473807fad8fd\", \"elevation_service_unittests\": \"cbeac994256f7b42538f5bb410d2bce5f28f6510\", \"events_unittests\": \"2feb34571ec1e51bc983db714664057a0d286aa1\", \"extensions_browsertests\": \"21aa2bb01352f9e23adae9df930295bb5acffd34\", \"extensions_unittests\": \"e22efb4de7fb87ed09a64ea6348e9bb1a60eba44\", \"filesystem_service_unittests\": \"a7720d53883e355bd5bc8a2af10c82e813b5537f\", \"gcm_unit_tests\": \"4333cf9d6d9b8847f11fea24426bca9524f58aab\", \"gcp_unittests\": \"056bb1076036a98dc82e7c28b8e7f0783fa1e579\", \"gfx_unittests\": \"804bffc4c4354c46b7b86006b574c89b51d7835e\", \"gin_unittests\": \"da9038849a09bda1a238d31ea8c69a99e0941d1a\", \"google_apis_unittests\": \"e242cc41510251c0ad0c4c75df4d8dd68eb9c92b\", \"gpu_unittests\": \"d8df69a179bdd822de29cb33a47c252c9fe1f4e6\", \"gwp_asan_unittests\": \"4a7320bb1349d7aab1b0fb054f650ca1d4f0358a\", \"headless_browsertests\": \"d803ae00352b8e830c9db5a426f6886d83865519\", \"headless_unittests\": \"4e43ab9e6c2c0c10db079d0be53d3a098057ab5b\", \"install_static_unittests\": \"2291d0114c56d95c7a244ea7db5fa559494a09dc\", \"installer_util_unittests\": \"b7270e196bcdc73b7e2a4257b017e8ab0969b3f7\", \"interactive_ui_tests\": \"2ad07708a67e2a329d69af2489bcd38486790b79\", \"ipc_tests\": \"6528afbf5f187ea8c8129cd9d33d4771c7b3bd67\", \"jingle_unittests\": \"abcd1591b33daa31030dd6ea288e8f77eadb27c5\", \"latency_unittests\": \"720d3e4741e885c0042d3d22f4d1f831c282961f\", \"libjingle_xmpp_unittests\": \"0423ad34155f84417a7bfea474907ffc7ca8366f\", \"liburlpattern_unittests\": \"daadf115d886fbacbadcbde1663ef20eff7c5f48\", \"media_blink_unittests\": \"ca947e44de4c1b1ef074149bfe67d7258a1a48ae\", \"media_unittests\": \"3686959b5fcd867bfe7a2ba3f67341a05e7ed478\", \"message_center_unittests\": \"80da8b26230fcf5370385e0715d3db7ddb181b95\", \"midi_unittests\": \"d23f03197260cc3f58b4db02e3690dd6c8a94e08\", \"mojo_core_unittests\": \"1ee8da492d059115d9c3cec123c9d177750d1392\", \"mojo_unittests\": \"7461e66b3e021a9f80405e743e1d0a81fdf872ec\", \"nacl_loader_unittests\": \"019e27b3ebf3107cbe5bc8becbeca0014faa5723\", \"native_theme_unittests\": \"18677a5734b3356960c707c620c07d0ca1e857eb\", \"net_unittests\": \"df7bbfeb0a6b6ea9365883a2a75ae0e1b8306c3f\", \"notification_helper_unittests\": \"3f9a8ce13e465b4f41d83d81c5aed5e9a8c44622\", \"pdf_unittests\": \"a23772cd374674d01a033944bdcb8ac706c294c6\", \"perfetto_unittests\": \"b3434d7937925ea1afef3a58863051918e319631\", \"ppapi_unittests\": \"2c57d0c2171d7a36acd9fdf7db14fe624b6f75b7\", \"printing_unittests\": \"a572c8cf99223814ac6b21f77bc386fc7294fc95\", \"remoting_unittests\": \"9640d74fe1bff0b8c52016bba43674207fcbe248\", \"sbox_integration_tests\": \"ddfca695b394ff1c98d19aa9f1822355a5da11ca\", \"sbox_unittests\": \"1ed162434a323cabe1c0b23239ff8af14c85cd7d\", \"sbox_validation_tests\": \"be9642ff11bd4a5d10bd907f82305ac606380c06\", \"service_manager_unittests\": \"4a8b1462140af98863f5f49b21c3f9d94ac057e5\", \"services_unittests\": \"af5dd925c9156ef13c95ddaf542a780a800e971f\", \"setup_unittests\": \"cc069b2271cbd5500b21a19ed8f29844c75b7432\", \"shell_dialogs_unittests\": \"e1909db8c53119683d18ccab354c41fb7bd0b9d6\", \"skia_unittests\": \"1afc2684bbd990b876396b673bfdccc956d1c8a7\", \"snapshot_unittests\": \"76d98fa3ecc3c7745d8affbf6074694863ad2571\", \"sql_unittests\": \"04b1baa32c8a84011b4f8f0f5d7eed505d1823ef\", \"storage_unittests\": \"d1689e144a0aa4322f3e3dee60cc7cea95c88bee\", \"sync_integration_tests\": \"8079b6bcfbeba3151df5a27efa317d3380cc0ef4\", \"traffic_annotation_auditor_unittests\": \"d0ba0b00f129b702abfeb8a1bfd9edee16059647\", \"ui_base_unittests\": \"4178ddec8700d57e150721c959e3808c445f4f9d\", \"ui_touch_selection_unittests\": \"7178e47d7e1b4718a3106e6dc91a3e888975464b\", \"unit_tests\": \"b1338a714ecb9e74d85f4ee0662780bca16509c1\", \"updater_tests\": \"abcd684f7bab158a4bbb9e033db9d61bc7dde76c\", \"url_unittests\": \"12d2cd786341299e76b4df7f0142796bd587efb8\", \"views_unittests\": \"ca2017cd69ed1e9bda61c0627f059572f045a5b5\", \"viz_unittests\": \"ebd86faf786674ad21e7db7f9284a044840b2ca2\", \"vr_common_unittests\": \"90c868ed7f150aa73b866bfced02782c918be838\", \"vr_pixeltests\": \"249ce6538b2ac2524964407f7929056a7acf8b1a\", \"weblayer_browsertests\": \"1a4e6767781a2a2d685472f7089c82700d0aad7f\", \"weblayer_unittests\": \"ab59c6cfe85ab0e2b1151f4a26103205fa0ccea2\", \"wm_unittests\": \"41ec288dcff23588bb66fe9dc9124571facb368c\", \"wtf_unittests\": \"f6e6c26ee3f38c06df50c1baedaf6ee227413391\", \"zlib_unittests\": \"a706157353924880c73ef3ac740c34e80c1608e0\", \"zucchini_unittests\": \"3a9898219a75d7d2130255f54da24b35673f8fa5\"}}, \"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-6-2gr3\"], \"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\": [\"5637-4d8b832\"], \"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": "1606237761567471",
"status": "COMPLETED",
"status_changed_ts": "1606241284503321",
"tags": [
"build_address:luci.chromium.ci/win-asan/15825",
"builder:win-asan",
"buildset:commit/git/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9d21717491f34a0b5d85f177ccc32d6efaec623",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028161420379210480",
"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/8862731358328388880/+/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:50140c9f3ea7cc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606241284509051",
"url": "https://ci.chromium.org/b/8862731358328388880",
"utcnow_ts": "1606241285126640"
}
}
[18:08:05.148] Invocation finished in 58m53.925434538s with status SUCCEEDED