[00:55:45.296] New invocation is queued and will start shortly
[00:55:46.449] Starting the invocation (attempt 1)
[00:55:46.498] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:55:46.498] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:55:46.498] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022786845468350944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"66998d9a52d092938735d3bd318aa6a2c8d8a31e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022786845468350944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"gitiles_ref:refs/heads/master"
]
}
[00:55:54.480] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611363347428394",
"id": "8857356782565149488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66998d9a52d092938735d3bd318aa6a2c8d8a31e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611363353397187",
"tags": [
"build_address:luci.chromium.ci/win-asan/16957",
"builder:win-asan",
"buildset:commit/git/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022786845468350944",
"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/8857356782565149488/+/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": "1611363353397356",
"url": "https://ci.chromium.org/b/8857356782565149488",
"utcnow_ts": "1611363354427496"
}
}
[00:55:54.480] Task URL: https://ci.chromium.org/b/8857356782565149488
[00:55:54.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:2:0) after 1m0s
[00:56:07.851] Received PubSub notification, asking Buildbucket for the build status
[00:56:08.609] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:54.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:2:0)
[00:56:54.604] Timer tick, asking Buildbucket for the build status
[00:56:54.851] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:54.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:4:0) after 1m0s
[00:57:55.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:4:0)
[00:57:55.015] Timer tick, asking Buildbucket for the build status
[00:57:56.480] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:56.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:5:0) after 1m0s
[00:58:56.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:5:0)
[00:58:56.578] Timer tick, asking Buildbucket for the build status
[00:58:57.148] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:6:0) after 1m0s
[00:59:57.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:6:0)
[00:59:57.217] Timer tick, asking Buildbucket for the build status
[00:59:57.565] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:57.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:7:0) after 1m0s
[01:00:57.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:7:0)
[01:00:57.670] Timer tick, asking Buildbucket for the build status
[01:00:57.816] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:57.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:8:0) after 1m0s
[01:01:58.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:8:0)
[01:01:58.070] Timer tick, asking Buildbucket for the build status
[01:01:58.335] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:58.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:9:0) after 1m0s
[01:02:58.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:9:0)
[01:02:58.512] Timer tick, asking Buildbucket for the build status
[01:02:59.536] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:59.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:10:0) after 1m0s
[01:03:59.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:10:0)
[01:03:59.644] Timer tick, asking Buildbucket for the build status
[01:03:59.997] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:59.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:11:0) after 1m0s
[01:05:00.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:11:0)
[01:05:00.054] Timer tick, asking Buildbucket for the build status
[01:05:00.216] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:00.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:12:0) after 1m0s
[01:06:00.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:12:0)
[01:06:00.320] Timer tick, asking Buildbucket for the build status
[01:06:00.827] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:00.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:13:0) after 1m0s
[01:07:00.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:13:0)
[01:07:00.916] Timer tick, asking Buildbucket for the build status
[01:07:01.021] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:01.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:14:0) after 1m0s
[01:08:01.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:14:0)
[01:08:01.164] Timer tick, asking Buildbucket for the build status
[01:08:01.781] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:01.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:15:0) after 1m0s
[01:09:01.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:15:0)
[01:09:01.902] Timer tick, asking Buildbucket for the build status
[01:09:02.012] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:02.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:16:0) after 1m0s
[01:10:02.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:16:0)
[01:10:02.123] Timer tick, asking Buildbucket for the build status
[01:10:02.450] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:02.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:17:0) after 1m0s
[01:11:02.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:17:0)
[01:11:02.513] Timer tick, asking Buildbucket for the build status
[01:11:02.683] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:02.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:18:0) after 1m0s
[01:12:02.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:18:0)
[01:12:02.737] Timer tick, asking Buildbucket for the build status
[01:12:03.113] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:03.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:19:0) after 1m0s
[01:13:03.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:19:0)
[01:13:03.160] Timer tick, asking Buildbucket for the build status
[01:13:03.376] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:03.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:20:0) after 1m0s
[01:14:03.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:20:0)
[01:14:03.406] Timer tick, asking Buildbucket for the build status
[01:14:04.825] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:04.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:21:0) after 1m0s
[01:15:04.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:21:0)
[01:15:04.855] Timer tick, asking Buildbucket for the build status
[01:15:05.288] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:05.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:22:0) after 1m0s
[01:16:05.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:22:0)
[01:16:05.337] Timer tick, asking Buildbucket for the build status
[01:16:05.648] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:05.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:23:0) after 1m0s
[01:17:05.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:23:0)
[01:17:05.669] Timer tick, asking Buildbucket for the build status
[01:17:06.011] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:06.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:24:0) after 1m0s
[01:18:05.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:24:0)
[01:18:05.995] Timer tick, asking Buildbucket for the build status
[01:18:06.078] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:06.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:25:0) after 1m0s
[01:19:06.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:25:0)
[01:19:06.141] Timer tick, asking Buildbucket for the build status
[01:19:07.019] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:07.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:26:0) after 1m0s
[01:20:07.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:26:0)
[01:20:07.096] Timer tick, asking Buildbucket for the build status
[01:20:07.372] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:07.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:27:0) after 1m0s
[01:21:07.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:27:0)
[01:21:07.428] Timer tick, asking Buildbucket for the build status
[01:21:07.936] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:07.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:28:0) after 1m0s
[01:22:07.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:28:0)
[01:22:07.974] Timer tick, asking Buildbucket for the build status
[01:22:11.604] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:11.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:29:0) after 1m0s
[01:23:11.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:29:0)
[01:23:11.661] Timer tick, asking Buildbucket for the build status
[01:23:11.957] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:11.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:30:0) after 1m0s
[01:24:12.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:30:0)
[01:24:12.012] Timer tick, asking Buildbucket for the build status
[01:24:12.236] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:12.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:31:0) after 1m0s
[01:25:12.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:31:0)
[01:25:12.331] Timer tick, asking Buildbucket for the build status
[01:25:12.499] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:12.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:32:0) after 1m0s
[01:26:12.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:32:0)
[01:26:12.577] Timer tick, asking Buildbucket for the build status
[01:26:14.112] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:33:0) after 1m0s
[01:27:14.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:33:0)
[01:27:14.113] Timer tick, asking Buildbucket for the build status
[01:27:14.969] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:14.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:34:0) after 1m0s
[01:28:15.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:34:0)
[01:28:15.069] Timer tick, asking Buildbucket for the build status
[01:28:15.266] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:15.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:35:0) after 1m0s
[01:29:15.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:35:0)
[01:29:15.310] Timer tick, asking Buildbucket for the build status
[01:29:15.473] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:15.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:36:0) after 1m0s
[01:30:15.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:36:0)
[01:30:15.556] Timer tick, asking Buildbucket for the build status
[01:30:16.102] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:16.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:37:0) after 1m0s
[01:31:16.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:37:0)
[01:31:16.148] Timer tick, asking Buildbucket for the build status
[01:31:16.942] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:16.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:38:0) after 1m0s
[01:32:16.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:38:0)
[01:32:16.991] Timer tick, asking Buildbucket for the build status
[01:32:17.094] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:17.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:39:0) after 1m0s
[01:33:17.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:39:0)
[01:33:17.129] Timer tick, asking Buildbucket for the build status
[01:33:17.523] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:17.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:40:0) after 1m0s
[01:34:17.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:40:0)
[01:34:17.598] Timer tick, asking Buildbucket for the build status
[01:34:17.828] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:17.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:41:0) after 1m0s
[01:35:17.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:41:0)
[01:35:17.861] Timer tick, asking Buildbucket for the build status
[01:35:18.074] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:18.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:42:0) after 1m0s
[01:36:18.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:42:0)
[01:36:18.152] Timer tick, asking Buildbucket for the build status
[01:36:18.369] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:43:0) after 1m0s
[01:37:18.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:43:0)
[01:37:18.452] Timer tick, asking Buildbucket for the build status
[01:37:18.664] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:44:0) after 1m0s
[01:38:18.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:44:0)
[01:38:18.735] Timer tick, asking Buildbucket for the build status
[01:38:18.838] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:18.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:45:0) after 1m0s
[01:39:18.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:45:0)
[01:39:18.855] Timer tick, asking Buildbucket for the build status
[01:39:19.193] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:19.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:46:0) after 1m0s
[01:40:19.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:46:0)
[01:40:19.275] Timer tick, asking Buildbucket for the build status
[01:40:19.493] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:47:0) after 1m0s
[01:41:19.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:47:0)
[01:41:19.595] Timer tick, asking Buildbucket for the build status
[01:41:19.791] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:48:0) after 1m0s
[01:42:19.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:48:0)
[01:42:19.827] Timer tick, asking Buildbucket for the build status
[01:42:20.223] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:20.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:49:0) after 1m0s
[01:43:20.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:49:0)
[01:43:20.246] Timer tick, asking Buildbucket for the build status
[01:43:20.818] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:20.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:50:0) after 1m0s
[01:44:20.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:50:0)
[01:44:20.842] Timer tick, asking Buildbucket for the build status
[01:44:21.077] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:21.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:51:0) after 1m0s
[01:45:21.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:51:0)
[01:45:21.110] Timer tick, asking Buildbucket for the build status
[01:45:21.567] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:21.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:52:0) after 1m0s
[01:46:21.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:52:0)
[01:46:21.585] Timer tick, asking Buildbucket for the build status
[01:46:22.404] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:53:0) after 1m0s
[01:47:22.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:53:0)
[01:47:22.437] Timer tick, asking Buildbucket for the build status
[01:47:22.640] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:22.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:54:0) after 1m0s
[01:48:22.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:54:0)
[01:48:22.664] Timer tick, asking Buildbucket for the build status
[01:48:22.839] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:55:0) after 1m0s
[01:49:22.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:55:0)
[01:49:22.920] Timer tick, asking Buildbucket for the build status
[01:49:23.029] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:23.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:56:0) after 1m0s
[01:50:23.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:56:0)
[01:50:23.186] Timer tick, asking Buildbucket for the build status
[01:50:23.338] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:23.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:57:0) after 1m0s
[01:51:23.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:57:0)
[01:51:23.379] Timer tick, asking Buildbucket for the build status
[01:51:23.700] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:23.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:58:0) after 1m0s
[01:52:23.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:58:0)
[01:52:23.691] Timer tick, asking Buildbucket for the build status
[01:52:24.750] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:24.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:59:0) after 1m0s
[01:53:25.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:59:0)
[01:53:25.186] Timer tick, asking Buildbucket for the build status
[01:53:25.370] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:25.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:60:0) after 1m0s
[01:54:25.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:60:0)
[01:54:25.487] Timer tick, asking Buildbucket for the build status
[01:54:25.623] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:25.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:61:0) after 1m0s
[01:55:25.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:61:0)
[01:55:25.678] Timer tick, asking Buildbucket for the build status
[01:55:25.770] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:25.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:62:0) after 1m0s
[01:56:25.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:62:0)
[01:56:25.924] Timer tick, asking Buildbucket for the build status
[01:56:26.105] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:26.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:63:0) after 1m0s
[01:57:26.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:63:0)
[01:57:26.143] Timer tick, asking Buildbucket for the build status
[01:57:26.293] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:26.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:64:0) after 1m0s
[01:58:26.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:64:0)
[01:58:26.402] Timer tick, asking Buildbucket for the build status
[01:58:26.477] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:26.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:65:0) after 1m0s
[01:59:26.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:65:0)
[01:59:26.639] Timer tick, asking Buildbucket for the build status
[01:59:26.790] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:26.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:66:0) after 1m0s
[02:00:27.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:66:0)
[02:00:27.053] Timer tick, asking Buildbucket for the build status
[02:00:27.350] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:27.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:67:0) after 1m0s
[02:01:27.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:67:0)
[02:01:27.389] Timer tick, asking Buildbucket for the build status
[02:01:28.404] Build 8857356782565149488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:28.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022786845468350944:68:0) after 1m0s
[02:01:56.104] Received PubSub notification, asking Buildbucket for the build status
[02:01:56.192] Build 8857356782565149488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:01:56.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611367313525200",
"created_by": "project:chromium",
"created_ts": "1611363347428394",
"id": "8857356782565149488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66998d9a52d092938735d3bd318aa6a2c8d8a31e\"}}",
"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\": \"66998d9a52d092938735d3bd318aa6a2c8d8a31e\", \"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-2zr0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16957, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"2c32fa6476085fce294e4d8be4ae6aed92240caa\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"66998d9a52d092938735d3bd318aa6a2c8d8a31e\", \"got_revision_cp\": \"refs/heads/master@{#846423}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"53d05d723a6a1f09bb5c642abd20fd987f0f3c93\", \"got_v8_revision_cp\": \"refs/heads/9.0.54@{#1}\", \"got_webrtc_revision\": \"8df643b38785f9b6fe8ae4bbf295839d28136d08\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33058}\", \"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\": \"66998d9a52d092938735d3bd318aa6a2c8d8a31e\", \"swarm_hashes_refs/heads/master(at){#846423}_without_patch\": {\"absl_hardening_tests\": \"288c9ec33d9be6b0afcb600b4c5d14f44c9eab3c\", \"accessibility_unittests\": \"fa5eefe7e13f8eeb8744492aa3ed0a9e57e7f6a5\", \"angle_unittests\": \"5dfb9f30774887c5bad8168f3c480f6afd745213\", \"app_shell_unittests\": \"b610e315a3b7d04bb35ff8aa5ecdd75d3851a950\", \"aura_unittests\": \"6d6338101c8f1783083462323e9bc80dbc27bdca\", \"base_unittests\": \"6e7fea2e8fea9b718e48754fccc9fdb077c5f85e\", \"base_util_unittests\": \"b9da58e3d4f01f1622a3f46b750bc112aa6cf0b2\", \"blink_common_unittests\": \"7ca0aaa9200e53ed5eee72fb868c54aa346bf91a\", \"blink_fuzzer_unittests\": \"c928f4d5b20660cbddf62820293038672dedb43e\", \"blink_heap_unittests\": \"1f3059c6bf066009fe1ead39104f6ca3b3a691b9\", \"blink_platform_unittests\": \"eb7ca1225bd334c2522c68c58a7efd3250f22ee6\", \"blink_unittests\": \"39ed3e4ab872791c1a5d0306ac85c5d4e73f9393\", \"boringssl_crypto_tests\": \"28b72d0357e52da3220f7a043de0b606229ea049\", \"boringssl_ssl_tests\": \"315798d94483f57515e6857b49d43d9fa43cf06d\", \"browser_switcher_bho_unittests\": \"0172f9f490a4f59b2744b8ee89fb3dcebaf5738f\", \"browser_tests\": \"7eea64ee1169cee43e45110ce999fa6a43d275a3\", \"capture_unittests\": \"ecf0c9362e9bac8377883e25e000844524672b3d\", \"cast_unittests\": \"232882183df8f8659eca395111f96a7adf1b4ace\", \"cc_unittests\": \"dd7f65a3e5b2a59a19fc8408b73d11c7d3d5a4b6\", \"chrome_app_unittests\": \"28ac757e049ee01b3cc1fff252c707018d96d128\", \"chrome_cleaner_unittests\": \"3be93ee1d734964175e7099b9a20cdfc2094cd50\", \"chrome_elf_unittests\": \"0e0cbb8a76ada5932c0dfc65083be5608e44f9c8\", \"chromedriver_unittests\": \"69f88ff9b41d13bc8a7b317f859426c1a123e493\", \"components_browsertests\": \"7697b5b946726cc21923bfa9cdb809307ca3ef50\", \"components_unittests\": \"bd4572241460c5ec1a36e5cd14c84efee4f15cfe\", \"compositor_unittests\": \"f81f45e68f3e6888d8ea59f1c880495a941d67ca\", \"content_browsertests\": \"df9ef90ef6b2b3f11e455ceb23dfed365ae76512\", \"content_unittests\": \"ab9d3b8b1ceda64e09276196b0515db971797484\", \"courgette_unittests\": \"491960638b8cc59a1d465a5d12e637e069df8eac\", \"crashpad_tests\": \"93f006a8965c43671b7c8b1c2905bb80c568e744\", \"cronet_tests\": \"48e46d1dd9f48501c336c8aa449f1ceb4ab89318\", \"cronet_unittests\": \"c324722a1b14014eef8281365e1ff6981d475e4f\", \"crypto_unittests\": \"f16bc6c025e92568772294818b6eee6467aae37e\", \"delayloads_unittests\": \"c0ef6c9bee385e22f7432903f5c6b064d8a15bc2\", \"device_unittests\": \"e60869990705677a1be843a1e738930ff2012733\", \"display_unittests\": \"a0da56b4d42e7ee25d27ccbfacde162c0a551408\", \"elevation_service_unittests\": \"64ea2f0cc7a865ae64645d6f7567ff840dd21052\", \"events_unittests\": \"0f4a88389f69641abd8f812a85c04ca965614a73\", \"extensions_browsertests\": \"642fffa66619b16e52ff52fe2d060add5ebc9bdc\", \"extensions_unittests\": \"2dbefb3d7377f3e2cd791e2b4337bec48556b34d\", \"filesystem_service_unittests\": \"f1e9dc675932dd05c0f3415e573ed585f6549adc\", \"gcm_unit_tests\": \"66626e94a794fa5f581ff4d6141b4aebb3824abb\", \"gcp_unittests\": \"0850d37e84b59035b5462c7b7c68a2d114b0ce38\", \"gfx_unittests\": \"cabcbc0deb5a98096b1dfffa0b9a8748f71106a0\", \"gin_unittests\": \"9482d55f65733f65c0c1608e52069ba7a37ab34d\", \"google_apis_unittests\": \"063380908f3cfed4d6c483c71568c5c3352071db\", \"gpu_unittests\": \"dbc7f84acecab087b0638679f6c54827eedf97e3\", \"gwp_asan_unittests\": \"f1f031537e7b954113d805733001cb192a0ac2f0\", \"headless_browsertests\": \"8e890f2e7d77b0d193581efcfab0929d0ba766eb\", \"headless_unittests\": \"03cdf38ceea4d2d7fa9928d17e188c69d5b13fab\", \"install_static_unittests\": \"88b40ee54e48fbbd2ebf9fcaa2e48d4223aa0178\", \"installer_util_unittests\": \"71a1aa20c6a185a574b34caf73812f8fed4aa517\", \"interactive_ui_tests\": \"209231d09b2e3c67e90aaa9974b596631fdc42af\", \"ipc_tests\": \"637108689f643b275b75d4b274ca012d22800694\", \"jingle_unittests\": \"94a5ca5f203e63e19547ddc02309762025fc4e23\", \"latency_unittests\": \"a99ecba4589f48aa3ec87dafcbf249b1c7d81cbf\", \"libjingle_xmpp_unittests\": \"f76b241f35b1a0e5d601068cc09f6875c7377fa4\", \"liburlpattern_unittests\": \"1b443db739a714a987d63c5ec8e26368f956ebbe\", \"media_blink_unittests\": \"e73e5e1e563798043bd0a0fee6d741089b12f3ef\", \"media_unittests\": \"f1d0b265712fc298e0f3496d6854ea984bd8512e\", \"message_center_unittests\": \"abffaf704823dbf9a861fd64c2260f1ae73638c4\", \"midi_unittests\": \"6b30423d9fc8ccb323c6f0c62205eb0478b73980\", \"mojo_core_unittests\": \"235d2ecdc3ac5744e0ca4c5860b0d1cdd453a117\", \"mojo_unittests\": \"7e8d865a065dd12433eae5e3dfe4e8a2da118477\", \"nacl_loader_unittests\": \"9d495e4dccada194cc0cd2dacc5de7f3b3d4201b\", \"native_theme_unittests\": \"507e3de453b6f78c18693eac830ac8d197707390\", \"net_unittests\": \"d969f7e38d4400ad54626241ddf4d4243a03a43e\", \"notification_helper_unittests\": \"b2d34dccbe72c46f5e5f84c4de5b6717a36c2421\", \"pdf_unittests\": \"4de0704f16ba72d093e66f46cc06c501dc9f3e8f\", \"perfetto_unittests\": \"5918156250065778fd1b72ab551f79c32b6b0745\", \"ppapi_unittests\": \"6fc0ce858f71bdf05a91d715abea592eba39b64c\", \"printing_unittests\": \"1b68d905dcbbd1e0d3bd4cd56d2dd2005d4a8fbd\", \"remoting_unittests\": \"34fb9a22565416d9c272a7fb444ef21360df2bf1\", \"sbox_integration_tests\": \"08f528fd3fd3d11c86ad3f820cf6621d08656614\", \"sbox_unittests\": \"1f9f232817cb1a0007ccc4b7c6605ebd3eb5cd06\", \"sbox_validation_tests\": \"72c952300fb60a237c5d0c80eff094e1ff417252\", \"service_manager_unittests\": \"4591d5a77449de07145ab46a3362098e8b717d0d\", \"services_unittests\": \"eb6456d7d942c4cc91376b5c4c850206598706e9\", \"setup_unittests\": \"6edc25c159b64939909f75ef84962d834cb5d9ba\", \"shell_dialogs_unittests\": \"35a9db321dd9418d8a9d3be385eed7bd2ddd3207\", \"skia_unittests\": \"ab47ad1312477ca4c657384d77125bbf77ce8833\", \"snapshot_unittests\": \"484158ee0921c0b9a25b74c9d649b7e485342692\", \"sql_unittests\": \"81f720e221472d604f8ca7ed6e6fc63c10e1a297\", \"storage_unittests\": \"4c6dc332fa725eec0b828826e10db42435647083\", \"sync_integration_tests\": \"e938b206f5f16d946eeaa63f5f7e02bbde380287\", \"traffic_annotation_auditor_unittests\": \"1814610ad0da0417fe17bbc86154cdcab33eab6f\", \"ui_base_unittests\": \"17c76ee162775ed0f6f91bee9f5a39da3f2f0a8c\", \"ui_touch_selection_unittests\": \"06dcb1cc9e2fa6d4f3183d027b0226a1444ca975\", \"unit_tests\": \"c863d626dcdc7f13a19aeb5c7b0e31a2f6db5201\", \"updater_tests\": \"e137ef6143e14e350b1871563e55a279e36dff96\", \"url_unittests\": \"2e6f30dc3359efd2915ed7668189a7f524745545\", \"views_unittests\": \"22465eb9ad57a32137398aa6c165a16121f0cd6f\", \"viz_unittests\": \"5cc60488a73e0685b139649a7832dcfeeec1739f\", \"vr_common_unittests\": \"137a3fe462a21dbb09bd52918bf8e64178fd7c40\", \"vr_pixeltests\": \"5d7df860fb820621a51d18578241f34d0bb4d0f3\", \"weblayer_browsertests\": \"7786f5667948548dc28b0933f092f1344d228f80\", \"weblayer_unittests\": \"cb982310f190b497d399110f79a1f2140b4d9bb8\", \"wm_unittests\": \"275a53abe9948cf875f44d046b7861f71e420794\", \"wtf_unittests\": \"c12bbdc135fc04ec936946e35cd942f9161a61dc\", \"zlib_unittests\": \"b755dd930688c93cc27c3fa568d3a6040e4f121e\", \"zucchini_unittests\": \"14a55a7299c1d07241d69d7cc220806a5c8aed87\"}}, \"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-2-2zr0\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611363363186518",
"status": "COMPLETED",
"status_changed_ts": "1611367314210352",
"tags": [
"build_address:luci.chromium.ci/win-asan/16957",
"builder:win-asan",
"buildset:commit/git/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66998d9a52d092938735d3bd318aa6a2c8d8a31e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022786845468350944",
"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/8857356782565149488/+/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:51458f0dc9887d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611367314221269",
"url": "https://ci.chromium.org/b/8857356782565149488",
"utcnow_ts": "1611367316179824"
}
}
[02:01:56.192] Invocation finished in 1h6m10.915889223s with status SUCCEEDED