[02:31:15.155] New invocation is queued and will start shortly
[02:31:16.299] Starting the invocation (attempt 1)
[02:31:16.353] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:31:16.353] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:31:16.353] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025589343229308672",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a634bba2980eb6ac3fe962cd3f2a741544287c3e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025589343229308672",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"gitiles_ref:refs/heads/master"
]
}
[02:31:17.595] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1608690676513049",
"id": "8860159281143112976",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a634bba2980eb6ac3fe962cd3f2a741544287c3e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608690677341310",
"tags": [
"build_address:luci.chromium.ci/win-asan/16397",
"builder:win-asan",
"buildset:commit/git/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025589343229308672",
"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/8860159281143112976/+/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": "1608690677341418",
"url": "https://ci.chromium.org/b/8860159281143112976",
"utcnow_ts": "1608690677580671"
}
}
[02:31:17.595] Task URL: https://ci.chromium.org/b/8860159281143112976
[02:31:17.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:2:0) after 1m0s
[02:31:22.825] Received PubSub notification, asking Buildbucket for the build status
[02:31:22.891] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:17.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:2:0)
[02:32:17.600] Timer tick, asking Buildbucket for the build status
[02:32:17.999] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:17.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:4:0) after 1m0s
[02:33:18.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:4:0)
[02:33:18.142] Timer tick, asking Buildbucket for the build status
[02:33:18.930] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:18.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:5:0) after 1m0s
[02:34:19.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:5:0)
[02:34:19.055] Timer tick, asking Buildbucket for the build status
[02:34:19.126] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:19.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:6:0) after 1m0s
[02:35:19.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:6:0)
[02:35:19.575] Timer tick, asking Buildbucket for the build status
[02:35:19.714] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:19.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:7:0) after 1m0s
[02:36:19.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:7:0)
[02:36:19.847] Timer tick, asking Buildbucket for the build status
[02:36:20.056] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:8:0) after 1m0s
[02:37:22.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:8:0)
[02:37:22.761] Timer tick, asking Buildbucket for the build status
[02:37:22.858] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:22.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:9:0) after 1m0s
[02:38:22.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:9:0)
[02:38:22.887] Timer tick, asking Buildbucket for the build status
[02:38:23.004] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:23.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:10:0) after 1m0s
[02:39:23.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:10:0)
[02:39:23.008] Timer tick, asking Buildbucket for the build status
[02:39:23.075] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:11:0) after 1m0s
[02:40:23.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:11:0)
[02:40:23.146] Timer tick, asking Buildbucket for the build status
[02:40:23.235] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:23.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:12:0) after 1m0s
[02:41:23.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:12:0)
[02:41:23.260] Timer tick, asking Buildbucket for the build status
[02:41:23.394] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:23.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:13:0) after 1m0s
[02:42:23.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:13:0)
[02:42:23.502] Timer tick, asking Buildbucket for the build status
[02:42:23.586] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:23.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:14:0) after 1m0s
[02:43:23.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:14:0)
[02:43:23.606] Timer tick, asking Buildbucket for the build status
[02:43:23.790] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:23.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:15:0) after 1m0s
[02:44:23.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:15:0)
[02:44:23.929] Timer tick, asking Buildbucket for the build status
[02:44:24.130] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:24.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:16:0) after 1m0s
[02:45:24.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:16:0)
[02:45:24.207] Timer tick, asking Buildbucket for the build status
[02:45:24.350] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:24.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:17:0) after 1m0s
[02:46:24.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:17:0)
[02:46:24.438] Timer tick, asking Buildbucket for the build status
[02:46:24.545] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:24.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:18:0) after 1m0s
[02:47:24.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:18:0)
[02:47:24.582] Timer tick, asking Buildbucket for the build status
[02:47:25.158] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:25.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:19:0) after 1m0s
[02:48:25.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:19:0)
[02:48:25.216] Timer tick, asking Buildbucket for the build status
[02:48:25.723] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:25.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:20:0) after 1m0s
[02:49:25.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:20:0)
[02:49:25.791] Timer tick, asking Buildbucket for the build status
[02:49:26.012] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:26.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:21:0) after 1m0s
[02:50:26.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:21:0)
[02:50:26.053] Timer tick, asking Buildbucket for the build status
[02:50:26.574] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:26.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:22:0) after 1m0s
[02:51:26.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:22:0)
[02:51:26.668] Timer tick, asking Buildbucket for the build status
[02:51:26.831] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:26.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:23:0) after 1m0s
[02:52:26.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:23:0)
[02:52:26.911] Timer tick, asking Buildbucket for the build status
[02:52:27.065] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:27.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:24:0) after 1m0s
[02:53:27.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:24:0)
[02:53:27.047] Timer tick, asking Buildbucket for the build status
[02:53:27.196] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:27.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:25:0) after 1m0s
[02:54:27.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:25:0)
[02:54:27.256] Timer tick, asking Buildbucket for the build status
[02:54:27.390] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:27.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:26:0) after 1m0s
[02:55:27.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:26:0)
[02:55:27.479] Timer tick, asking Buildbucket for the build status
[02:55:30.047] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:30.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:27:0) after 1m0s
[02:56:30.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:27:0)
[02:56:30.122] Timer tick, asking Buildbucket for the build status
[02:56:30.255] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:30.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:28:0) after 1m0s
[02:57:30.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:28:0)
[02:57:30.354] Timer tick, asking Buildbucket for the build status
[02:57:30.437] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:30.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:29:0) after 1m0s
[02:58:30.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:29:0)
[02:58:30.572] Timer tick, asking Buildbucket for the build status
[02:58:30.646] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:30.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:30:0) after 1m0s
[02:59:30.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:30:0)
[02:59:30.772] Timer tick, asking Buildbucket for the build status
[02:59:30.860] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:30.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:31:0) after 1m0s
[03:00:30.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:31:0)
[03:00:30.925] Timer tick, asking Buildbucket for the build status
[03:00:31.001] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:31.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:32:0) after 1m0s
[03:01:31.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:32:0)
[03:01:31.053] Timer tick, asking Buildbucket for the build status
[03:01:31.235] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:31.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:33:0) after 1m0s
[03:02:31.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:33:0)
[03:02:31.270] Timer tick, asking Buildbucket for the build status
[03:02:31.403] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:31.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:34:0) after 1m0s
[03:03:31.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:34:0)
[03:03:31.411] Timer tick, asking Buildbucket for the build status
[03:03:31.494] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:31.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:35:0) after 1m0s
[03:04:31.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:35:0)
[03:04:31.511] Timer tick, asking Buildbucket for the build status
[03:04:31.612] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:31.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:36:0) after 1m0s
[03:05:31.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:36:0)
[03:05:31.654] Timer tick, asking Buildbucket for the build status
[03:05:31.730] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:31.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:37:0) after 1m0s
[03:06:31.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:37:0)
[03:06:31.805] Timer tick, asking Buildbucket for the build status
[03:06:31.938] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:31.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:38:0) after 1m0s
[03:07:32.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:38:0)
[03:07:32.109] Timer tick, asking Buildbucket for the build status
[03:07:32.401] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:32.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:39:0) after 1m0s
[03:08:32.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:39:0)
[03:08:32.517] Timer tick, asking Buildbucket for the build status
[03:08:32.688] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:32.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:40:0) after 1m0s
[03:09:32.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:40:0)
[03:09:32.759] Timer tick, asking Buildbucket for the build status
[03:09:32.841] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:32.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:41:0) after 1m0s
[03:10:32.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:41:0)
[03:10:32.867] Timer tick, asking Buildbucket for the build status
[03:10:33.143] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:33.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:42:0) after 1m0s
[03:11:33.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:42:0)
[03:11:33.203] Timer tick, asking Buildbucket for the build status
[03:11:33.351] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:33.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:43:0) after 1m0s
[03:12:33.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:43:0)
[03:12:33.470] Timer tick, asking Buildbucket for the build status
[03:12:33.584] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:33.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:44:0) after 1m0s
[03:13:33.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:44:0)
[03:13:33.602] Timer tick, asking Buildbucket for the build status
[03:13:34.388] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:34.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:45:0) after 1m0s
[03:14:34.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:45:0)
[03:14:34.462] Timer tick, asking Buildbucket for the build status
[03:14:34.625] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:34.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:46:0) after 1m0s
[03:15:34.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:46:0)
[03:15:34.619] Timer tick, asking Buildbucket for the build status
[03:15:34.822] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:34.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:47:0) after 1m0s
[03:16:34.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:47:0)
[03:16:34.910] Timer tick, asking Buildbucket for the build status
[03:16:35.553] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:35.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:48:0) after 1m0s
[03:17:35.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:48:0)
[03:17:35.607] Timer tick, asking Buildbucket for the build status
[03:17:35.748] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:35.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:49:0) after 1m0s
[03:18:36.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:49:0)
[03:18:36.005] Timer tick, asking Buildbucket for the build status
[03:18:36.164] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:36.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:50:0) after 1m0s
[03:19:36.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:50:0)
[03:19:36.296] Timer tick, asking Buildbucket for the build status
[03:19:36.510] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:51:0) after 1m0s
[03:20:36.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:51:0)
[03:20:36.618] Timer tick, asking Buildbucket for the build status
[03:20:36.685] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:36.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:52:0) after 1m0s
[03:21:36.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:52:0)
[03:21:36.729] Timer tick, asking Buildbucket for the build status
[03:21:36.846] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:36.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:53:0) after 1m0s
[03:22:36.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:53:0)
[03:22:36.956] Timer tick, asking Buildbucket for the build status
[03:22:37.299] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:37.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:54:0) after 1m0s
[03:23:37.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:54:0)
[03:23:37.401] Timer tick, asking Buildbucket for the build status
[03:23:37.471] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:37.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:55:0) after 1m0s
[03:24:37.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:55:0)
[03:24:37.648] Timer tick, asking Buildbucket for the build status
[03:24:37.733] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:37.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:56:0) after 1m0s
[03:25:37.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:56:0)
[03:25:37.812] Timer tick, asking Buildbucket for the build status
[03:25:37.877] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:37.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:57:0) after 1m0s
[03:26:37.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:57:0)
[03:26:37.940] Timer tick, asking Buildbucket for the build status
[03:26:38.151] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:38.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:58:0) after 1m0s
[03:27:38.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:58:0)
[03:27:38.311] Timer tick, asking Buildbucket for the build status
[03:27:38.428] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:38.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:59:0) after 1m0s
[03:28:38.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:59:0)
[03:28:38.478] Timer tick, asking Buildbucket for the build status
[03:28:38.756] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:38.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:60:0) after 1m0s
[03:29:38.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:60:0)
[03:29:38.829] Timer tick, asking Buildbucket for the build status
[03:29:39.401] Build 8860159281143112976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:39.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025589343229308672:61:0) after 1m0s
[03:30:08.978] Received PubSub notification, asking Buildbucket for the build status
[03:30:09.046] Build 8860159281143112976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:30:09.046] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1608694207378401",
"created_by": "project:chromium",
"created_ts": "1608690676513049",
"id": "8860159281143112976",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a634bba2980eb6ac3fe962cd3f2a741544287c3e\"}}",
"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\": \"a634bba2980eb6ac3fe962cd3f2a741544287c3e\", \"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-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16397, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"74788951af42f8f4f360bb9471cb3906379bdb7c\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a634bba2980eb6ac3fe962cd3f2a741544287c3e\", \"got_revision_cp\": \"refs/heads/master@{#839037}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"407251b826bdb2683e639456c81b4a82de640240\", \"got_v8_revision_cp\": \"refs/heads/8.9.185@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"a634bba2980eb6ac3fe962cd3f2a741544287c3e\", \"swarm_hashes_refs/heads/master(at){#839037}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"ad561ae33a785e132595d5e9507f91ef42db6209\", \"angle_unittests\": \"e2f544dff5a92f1c79a764780db3eaa30f31ec18\", \"app_shell_unittests\": \"c4db96a03f638ab72029e00ad637a1107688aec8\", \"aura_unittests\": \"6feb6bb1bb3ee097bcca1d36a9a436d96f423e7c\", \"base_unittests\": \"7ca7a1bb35aac42e0d7146e65d0f45bccbd8b1d1\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"879492e0d4afd9bd4baca9a0edbb61ca92057217\", \"blink_fuzzer_unittests\": \"c1dc6f413b6d706b07e6ad7ee512616ed359fe69\", \"blink_heap_unittests\": \"df24ca890b214e8f36c4cfdece395d5738023869\", \"blink_platform_unittests\": \"9a70428933c5d9ca7275a99e8fb02971c2f5889d\", \"blink_unittests\": \"b19f6f15e4c7adfc3f9f3872faf58304fbfd874e\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"dbb00d098ff19eb5cb73c82c05e1a2aa898db3a1\", \"capture_unittests\": \"5bea8d8e642526ac66d2803b12de97967e91bb05\", \"cast_unittests\": \"2f36504434bcfbc59d1729263500da80bdfdbff3\", \"cc_unittests\": \"d7913c55cfad7e4168c339638927ef54dfcf2e10\", \"chrome_app_unittests\": \"5b9434ec776a0a9b765fb001e964b8c988894226\", \"chrome_cleaner_unittests\": \"00b5bd9ae8b1f31c3f292706e2baf5239e4b862b\", \"chrome_elf_unittests\": \"efdbb495822466f26eea4d6d405259eda2466edc\", \"chromedriver_unittests\": \"d425d078222338172c607be04fc5ac01d1f2b9c9\", \"components_browsertests\": \"0c0bfc9b149b00fa94721fe0e8af498e972814ef\", \"components_unittests\": \"67ea387ab50743f7101988c8208afca756b72252\", \"compositor_unittests\": \"11bc4afd05c1ef7557bb1144639861a4f1b5d4ae\", \"content_browsertests\": \"9318c2601d49a0f6f9a5d1b9febbd483aa9d92c8\", \"content_unittests\": \"ce530803884f373c1f953666bb61209f72a72d06\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"c0648a2273e0ef08dd4ab6dc66522945d4ca9500\", \"cronet_unittests\": \"8ac5e7d54ecc9b76087038f60e0dd9431beceed5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"d4716558ea75834d86c82fae83dbffe1b4a6813e\", \"device_unittests\": \"1b792ec275cdfb006dada00d7829253f1fd8df8d\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"c3b52a4315a2c1a9a4e09dc7f09b65a0081ffc1b\", \"events_unittests\": \"6e6bdbcf641cb62ba4d2e82bc7a1b65bd4f42af6\", \"extensions_browsertests\": \"3f71b05645b3efcb591cae1a87844d27df8db75b\", \"extensions_unittests\": \"64203fd2f6770824f60b63fe0ee6e60d76c087b4\", \"filesystem_service_unittests\": \"3eb8b1d62971ac8f67f08f12aad80e546bd69cee\", \"gcm_unit_tests\": \"1369e42617f62f794284adca9527f8a92f5e5c10\", \"gcp_unittests\": \"a6d6ce523af9b34732defd50588dc7a1804bac91\", \"gfx_unittests\": \"2947c9fdb8958c15fc82928c113abe77569b1867\", \"gin_unittests\": \"214574e828a3ac1b84aa29ebd926f7a7aeec77cb\", \"google_apis_unittests\": \"6afb29a09265ab473cffbf1f9b6d49b3c5698866\", \"gpu_unittests\": \"7062379f5b18d0cfa573c38ec05973ce47ea2e69\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"408e227f7ef6864389fc3244c6879f607b737929\", \"headless_unittests\": \"3714f9719ccc1dfc2d1420d5717acd2847a574bc\", \"install_static_unittests\": \"1b62ab6bd1a8805e18f9a573b7d0e1303288a657\", \"installer_util_unittests\": \"189b9bab9c1a1b73bba2f87438a2407345b6ec8d\", \"interactive_ui_tests\": \"6abb82972e1ea97db0243e2ef3184d665b58d8bf\", \"ipc_tests\": \"1595c056c91c71330b7ce8f8cc4c9ccdbdd57df6\", \"jingle_unittests\": \"8e1835eb0231e18933681db5493c386f2886272a\", \"latency_unittests\": \"50f8f1841859350487c52ea0c05866a6c7484518\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"2df61ca9b34c127c76d2163e3d552cad9b512525\", \"media_unittests\": \"e3813451a70b80782fd8ca7bd0d28d0dcc24f524\", \"message_center_unittests\": \"3f1452849cc7662bf713851ffb8f6afd93aa4d79\", \"midi_unittests\": \"e6016c6619e2cab84be95ff46a15ad33888ecbad\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"7af3d6a39830117a066af809cc0a3e1376c934a5\", \"nacl_loader_unittests\": \"139391205b74c8ef9431db3152c1480a39933988\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"e1361e15832b684341c2dce97bd1a1fbe979fa05\", \"pdf_unittests\": \"63c7ac81c4ccc14d6c73748f60a7de0c8f51ba65\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"2963aa5b426caa106ecb66784ee16742e200b0c0\", \"printing_unittests\": \"52e536404df9ba20f0c5e30c2f273806c70acbc5\", \"remoting_unittests\": \"6fefd1f42a19c15379e212e9ea81be8f92f0a1d9\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"1bca802a776d68b2108f1cf6707688d9a4e82db0\", \"services_unittests\": \"4ed3205f5ecfd36b7125eda0ac6bffabefcafebd\", \"setup_unittests\": \"c27f8183bdcf1841da8b0a49e9f48a736d6b712a\", \"shell_dialogs_unittests\": \"133b936401abd71794f6c95b36c83b754f77dd62\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"8a4d29cffa4925459d4ad24cb11244a15058dc3b\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"95cc4182d2935a7442af0be16ce2aef861ebb7be\", \"sync_integration_tests\": \"52e0c6d0b494b3feeb88ba89fb49bf658b9a6d4c\", \"traffic_annotation_auditor_unittests\": \"076beb134bc27031f27b540ad1fed561057a2df7\", \"ui_base_unittests\": \"9bef2e0b2cf9951963cf6cabcdb5e7f811e69ca7\", \"ui_touch_selection_unittests\": \"06b82b59dbda518559f4317968008deabc501cb8\", \"unit_tests\": \"b30b0052f76cbafeba928f667ab9054d497f0bb4\", \"updater_tests\": \"0986ce86e6ce3768a76f3f3c2a04a80db7bf171f\", \"url_unittests\": \"c502b00c3b610b8a65f12f425a6311b885671b64\", \"views_unittests\": \"253f2f44fe0bb577d2818cf26c53c6b169e7bf25\", \"viz_unittests\": \"bb29486b3e15ba35bc536a027325ae40782e3b6b\", \"vr_common_unittests\": \"8e0d904ceff45aca03ea3748528e55336e187432\", \"vr_pixeltests\": \"2f0a767d2a602453c2c60516ba28f7f8e44431fe\", \"weblayer_browsertests\": \"5019d588cde9bd552e383d40a40556ed379cc920\", \"weblayer_unittests\": \"30668be694903e492ae7207c6408a4df6b0feaca\", \"wm_unittests\": \"490fe23f1f3c59c9019479e4dfedc9c7166bce2e\", \"wtf_unittests\": \"41a01c5b3405bdc35d5d7f89a1579ca6ab393d93\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"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": "1608690682247177",
"status": "COMPLETED",
"status_changed_ts": "1608694207728862",
"tags": [
"build_address:luci.chromium.ci/win-asan/16397",
"builder:win-asan",
"buildset:commit/git/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a634bba2980eb6ac3fe962cd3f2a741544287c3e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025589343229308672",
"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/8860159281143112976/+/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:50a6413fce1f4c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608694207733050",
"url": "https://ci.chromium.org/b/8860159281143112976",
"utcnow_ts": "1608694209036230"
}
}
[03:30:09.046] Invocation finished in 58m53.905868033s with status SUCCEEDED