[01:23:51.441] New invocation is queued and will start shortly
[01:23:53.376] Starting the invocation (attempt 1)
[01:23:53.411] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:23:53.411] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:23:53.411] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028492686301982960",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5cc24d18788ec6907336df45d34173f355bc5646\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028492686301982960",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5cc24d18788ec6907336df45d34173f355bc5646",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cc24d18788ec6907336df45d34173f355bc5646",
"gitiles_ref:refs/heads/master"
]
}
[01:23:54.415] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605921833593512",
"id": "8863062623376955120",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5cc24d18788ec6907336df45d34173f355bc5646\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605921834142890",
"tags": [
"build_address:luci.chromium.ci/win-asan/15750",
"builder:win-asan",
"buildset:commit/git/5cc24d18788ec6907336df45d34173f355bc5646",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cc24d18788ec6907336df45d34173f355bc5646",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028492686301982960",
"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/8863062623376955120/+/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": "1605921834143027",
"url": "https://ci.chromium.org/b/8863062623376955120",
"utcnow_ts": "1605921834401200"
}
}
[01:23:54.415] Task URL: https://ci.chromium.org/b/8863062623376955120
[01:23:54.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:2:0) after 1m0s
[01:23:58.090] Received PubSub notification, asking Buildbucket for the build status
[01:23:58.163] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:54.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:2:0)
[01:24:54.542] Timer tick, asking Buildbucket for the build status
[01:24:54.612] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:54.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:4:0) after 1m0s
[01:25:54.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:4:0)
[01:25:54.763] Timer tick, asking Buildbucket for the build status
[01:25:54.902] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:54.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:5:0) after 1m0s
[01:26:55.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:5:0)
[01:26:55.004] Timer tick, asking Buildbucket for the build status
[01:26:55.103] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:55.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:6:0) after 1m0s
[01:27:55.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:6:0)
[01:27:55.129] Timer tick, asking Buildbucket for the build status
[01:27:55.222] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:55.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:7:0) after 1m0s
[01:28:55.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:7:0)
[01:28:55.275] Timer tick, asking Buildbucket for the build status
[01:28:55.391] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:55.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:8:0) after 1m0s
[01:29:55.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:8:0)
[01:29:55.442] Timer tick, asking Buildbucket for the build status
[01:29:55.503] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:55.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:9:0) after 1m0s
[01:30:55.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:9:0)
[01:30:55.606] Timer tick, asking Buildbucket for the build status
[01:30:55.672] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:55.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:10:0) after 1m0s
[01:31:55.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:10:0)
[01:31:55.845] Timer tick, asking Buildbucket for the build status
[01:31:55.928] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:55.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:11:0) after 1m0s
[01:32:55.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:11:0)
[01:32:55.956] Timer tick, asking Buildbucket for the build status
[01:32:56.091] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:56.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:12:0) after 1m0s
[01:33:56.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:12:0)
[01:33:56.147] Timer tick, asking Buildbucket for the build status
[01:33:56.282] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:56.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:13:0) after 1m0s
[01:34:56.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:13:0)
[01:34:56.569] Timer tick, asking Buildbucket for the build status
[01:34:56.635] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:56.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:14:0) after 1m0s
[01:35:56.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:14:0)
[01:35:56.762] Timer tick, asking Buildbucket for the build status
[01:35:57.014] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:57.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:15:0) after 1m0s
[01:36:57.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:15:0)
[01:36:57.057] Timer tick, asking Buildbucket for the build status
[01:36:57.236] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:57.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:16:0) after 1m0s
[01:37:57.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:16:0)
[01:37:57.299] Timer tick, asking Buildbucket for the build status
[01:37:57.372] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:57.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:17:0) after 1m0s
[01:38:57.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:17:0)
[01:38:57.476] Timer tick, asking Buildbucket for the build status
[01:38:57.668] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:18:0) after 1m0s
[01:39:57.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:18:0)
[01:39:57.791] Timer tick, asking Buildbucket for the build status
[01:39:57.850] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:57.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:19:0) after 1m0s
[01:40:57.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:19:0)
[01:40:57.917] Timer tick, asking Buildbucket for the build status
[01:40:57.988] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:57.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:20:0) after 1m0s
[01:41:58.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:20:0)
[01:41:58.128] Timer tick, asking Buildbucket for the build status
[01:41:58.183] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:58.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:21:0) after 1m0s
[01:42:58.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:21:0)
[01:42:58.239] Timer tick, asking Buildbucket for the build status
[01:42:58.344] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:58.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:22:0) after 1m0s
[01:43:58.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:22:0)
[01:43:58.409] Timer tick, asking Buildbucket for the build status
[01:43:58.511] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:58.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:23:0) after 1m0s
[01:44:58.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:23:0)
[01:44:58.513] Timer tick, asking Buildbucket for the build status
[01:44:58.579] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:58.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:24:0) after 1m0s
[01:45:58.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:24:0)
[01:45:58.604] Timer tick, asking Buildbucket for the build status
[01:45:58.698] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:58.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:25:0) after 1m0s
[01:46:58.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:25:0)
[01:46:58.703] Timer tick, asking Buildbucket for the build status
[01:46:58.817] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:58.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:26:0) after 1m0s
[01:47:58.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:26:0)
[01:47:58.839] Timer tick, asking Buildbucket for the build status
[01:47:58.945] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:58.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:27:0) after 1m0s
[01:48:58.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:27:0)
[01:48:58.995] Timer tick, asking Buildbucket for the build status
[01:48:59.115] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:59.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:28:0) after 1m0s
[01:49:59.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:28:0)
[01:49:59.142] Timer tick, asking Buildbucket for the build status
[01:49:59.699] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:59.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:29:0) after 1m0s
[01:50:59.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:29:0)
[01:50:59.749] Timer tick, asking Buildbucket for the build status
[01:50:59.901] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:59.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:30:0) after 1m0s
[01:51:59.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:30:0)
[01:51:59.925] Timer tick, asking Buildbucket for the build status
[01:52:00.187] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:00.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:31:0) after 1m0s
[01:53:00.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:31:0)
[01:53:00.207] Timer tick, asking Buildbucket for the build status
[01:53:00.330] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:00.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:32:0) after 1m0s
[01:54:01.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:32:0)
[01:54:01.021] Timer tick, asking Buildbucket for the build status
[01:54:01.510] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:01.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:33:0) after 1m0s
[01:55:02.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:33:0)
[01:55:02.272] Timer tick, asking Buildbucket for the build status
[01:55:02.441] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:02.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:34:0) after 1m0s
[01:56:02.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:34:0)
[01:56:02.594] Timer tick, asking Buildbucket for the build status
[01:56:03.732] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:03.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:35:0) after 1m0s
[01:57:03.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:35:0)
[01:57:03.793] Timer tick, asking Buildbucket for the build status
[01:57:03.968] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:03.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:36:0) after 1m0s
[01:58:04.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:36:0)
[01:58:04.117] Timer tick, asking Buildbucket for the build status
[01:58:04.920] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:04.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:37:0) after 1m0s
[01:59:04.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:37:0)
[01:59:04.929] Timer tick, asking Buildbucket for the build status
[01:59:05.035] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:05.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:38:0) after 1m0s
[02:00:05.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:38:0)
[02:00:05.167] Timer tick, asking Buildbucket for the build status
[02:00:05.929] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:05.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:39:0) after 1m0s
[02:01:06.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:39:0)
[02:01:06.828] Timer tick, asking Buildbucket for the build status
[02:01:07.116] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:07.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:40:0) after 1m0s
[02:02:07.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:40:0)
[02:02:07.198] Timer tick, asking Buildbucket for the build status
[02:02:07.442] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:07.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:41:0) after 1m0s
[02:03:07.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:41:0)
[02:03:07.504] Timer tick, asking Buildbucket for the build status
[02:03:08.050] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:08.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:42:0) after 1m0s
[02:04:08.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:42:0)
[02:04:08.100] Timer tick, asking Buildbucket for the build status
[02:04:08.207] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:08.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:43:0) after 1m0s
[02:05:08.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:43:0)
[02:05:08.260] Timer tick, asking Buildbucket for the build status
[02:05:08.380] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:08.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:44:0) after 1m0s
[02:06:08.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:44:0)
[02:06:08.448] Timer tick, asking Buildbucket for the build status
[02:06:08.581] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:08.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:45:0) after 1m0s
[02:07:08.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:45:0)
[02:07:08.666] Timer tick, asking Buildbucket for the build status
[02:07:08.853] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:08.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:46:0) after 1m0s
[02:08:08.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:46:0)
[02:08:08.930] Timer tick, asking Buildbucket for the build status
[02:08:09.075] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:09.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:47:0) after 1m0s
[02:09:09.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:47:0)
[02:09:09.122] Timer tick, asking Buildbucket for the build status
[02:09:09.332] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:09.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:48:0) after 1m0s
[02:10:09.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:48:0)
[02:10:09.432] Timer tick, asking Buildbucket for the build status
[02:10:09.587] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:09.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:49:0) after 1m0s
[02:11:09.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:49:0)
[02:11:09.665] Timer tick, asking Buildbucket for the build status
[02:11:09.786] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:09.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:50:0) after 1m0s
[02:12:09.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:50:0)
[02:12:09.869] Timer tick, asking Buildbucket for the build status
[02:12:10.008] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:51:0) after 1m0s
[02:13:10.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:51:0)
[02:13:10.061] Timer tick, asking Buildbucket for the build status
[02:13:10.131] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:10.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:52:0) after 1m0s
[02:14:10.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:52:0)
[02:14:10.244] Timer tick, asking Buildbucket for the build status
[02:14:10.402] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:10.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:53:0) after 1m0s
[02:15:10.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:53:0)
[02:15:10.467] Timer tick, asking Buildbucket for the build status
[02:15:10.679] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:10.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:54:0) after 1m0s
[02:16:10.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:54:0)
[02:16:10.814] Timer tick, asking Buildbucket for the build status
[02:16:10.922] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:10.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:55:0) after 1m0s
[02:17:11.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:55:0)
[02:17:11.004] Timer tick, asking Buildbucket for the build status
[02:17:11.132] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:11.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:56:0) after 1m0s
[02:18:11.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:56:0)
[02:18:11.228] Timer tick, asking Buildbucket for the build status
[02:18:11.359] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:11.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:57:0) after 1m0s
[02:19:11.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:57:0)
[02:19:11.386] Timer tick, asking Buildbucket for the build status
[02:19:11.504] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:11.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:58:0) after 1m0s
[02:20:11.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:58:0)
[02:20:11.593] Timer tick, asking Buildbucket for the build status
[02:20:11.851] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:11.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:59:0) after 1m0s
[02:21:11.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:59:0)
[02:21:11.878] Timer tick, asking Buildbucket for the build status
[02:21:11.995] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:11.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:60:0) after 1m0s
[02:22:12.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:60:0)
[02:22:12.011] Timer tick, asking Buildbucket for the build status
[02:22:12.175] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:12.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:61:0) after 1m0s
[02:23:12.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:61:0)
[02:23:12.257] Timer tick, asking Buildbucket for the build status
[02:23:12.345] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:12.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:62:0) after 1m0s
[02:24:12.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:62:0)
[02:24:12.389] Timer tick, asking Buildbucket for the build status
[02:24:12.546] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:12.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:63:0) after 1m0s
[02:25:12.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:63:0)
[02:25:12.627] Timer tick, asking Buildbucket for the build status
[02:25:12.740] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:12.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:64:0) after 1m0s
[02:26:12.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:64:0)
[02:26:12.758] Timer tick, asking Buildbucket for the build status
[02:26:12.823] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:12.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:65:0) after 1m0s
[02:27:12.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:65:0)
[02:27:12.910] Timer tick, asking Buildbucket for the build status
[02:27:12.973] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:12.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:66:0) after 1m0s
[02:28:12.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:66:0)
[02:28:12.996] Timer tick, asking Buildbucket for the build status
[02:28:13.079] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:13.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:67:0) after 1m0s
[02:29:13.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:67:0)
[02:29:13.143] Timer tick, asking Buildbucket for the build status
[02:29:21.232] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:21.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:68:0) after 1m0s
[02:30:21.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:68:0)
[02:30:21.312] Timer tick, asking Buildbucket for the build status
[02:30:21.439] Build 8863062623376955120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:21.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028492686301982960:69:0) after 1m0s
[02:31:19.276] Received PubSub notification, asking Buildbucket for the build status
[02:31:19.589] Build 8863062623376955120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:31:19.589] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605925878180619",
"created_by": "project:chromium",
"created_ts": "1605921833593512",
"id": "8863062623376955120",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5cc24d18788ec6907336df45d34173f355bc5646\"}}",
"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\": \"5cc24d18788ec6907336df45d34173f355bc5646\", \"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-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15750, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"c4ca12e32e6aef9f5f8eb06c4b361264bec19f8d\", \"got_dawn_revision\": \"41b3f9c1e4aaaa3d56d205d81e61deaebdd40d75\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"5cc24d18788ec6907336df45d34173f355bc5646\", \"got_revision_cp\": \"refs/heads/master@{#829908}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"8a682e08571d84f1d93ef73a07cfac7a089676c9\", \"got_v8_revision_cp\": \"refs/heads/8.9.28@{#1}\", \"got_webrtc_revision\": \"ce4be1e64050943f47d5944dc0dead3875615184\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32657}\", \"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\": \"5cc24d18788ec6907336df45d34173f355bc5646\", \"swarm_hashes_refs/heads/master(at){#829908}_without_patch\": {\"absl_hardening_tests\": \"03f8cf3a2f4169627e0ed28ba69c5c84c44e9bca\", \"accessibility_unittests\": \"09613689c64129f1ffd0ebd79391802a2c8aa596\", \"angle_unittests\": \"a89ac004c14b22ea3057de966213082eddc2d610\", \"app_shell_unittests\": \"958308da7b26bcb84c570142d1ded44d4aaa1489\", \"aura_unittests\": \"e31956daf2febb536f7127b2579a559992df45eb\", \"base_unittests\": \"a0931dfe255989679b52c8f3c6fa88b13e2c8c7c\", \"base_util_unittests\": \"812836bc6dd6835f64320125857ead7b6fcb58da\", \"blink_common_unittests\": \"0a38e144e8cef19aee4b7e0d37e177756bd52db8\", \"blink_fuzzer_unittests\": \"56f140f6f7e5eee73b5f4a9349047e32538ec875\", \"blink_heap_unittests\": \"f21cbb5cea3e0f9cb6276bf954d315404de70a57\", \"blink_platform_unittests\": \"84ff168626fab7d260cf29d9b4f0b21155f1c45d\", \"blink_unittests\": \"118300ce08d03e2bd5064683cc70ed05c80c4440\", \"boringssl_crypto_tests\": \"437c75175903f17674a9426a491631b4128312e4\", \"boringssl_ssl_tests\": \"f4ff432e1af43e5acb487de694833bb9c814729b\", \"browser_switcher_bho_unittests\": \"919516cdd26c2924eef5e801aba557510c19e98d\", \"browser_tests\": \"85232fc656c992ea21f7d45aa61697d6c0644a9b\", \"capture_unittests\": \"3ac1b36a99e66945b0166b4e7ad1e83fe6799aa2\", \"cast_unittests\": \"b45da5d07ffc3873a71f4f5b5e09734d38f99ab6\", \"cc_unittests\": \"e317936cba0a902d90d1e4c195c7b301558973bc\", \"chrome_app_unittests\": \"4db2debb19ccd302b228e9a63eb4d84b3dc339be\", \"chrome_cleaner_unittests\": \"679252e1295bbc3fc288516011e7976a3f9666eb\", \"chrome_elf_unittests\": \"b1ddf7ad94c81d4eacf8b00789a06ff3ce247665\", \"chromedriver_unittests\": \"63c6a12c8541f28a0907c96a2928ae3732151717\", \"components_browsertests\": \"b1f92d1ad18cec9987b4d47b08189cc88eebcaa9\", \"components_unittests\": \"bd13f2e24b48d8ac0add97c96e22cd867006a95d\", \"compositor_unittests\": \"a74fc6a1ef28ebb75b2ddc5382a1d56c43dbcd53\", \"content_browsertests\": \"97b8cdfe9c5afe4b1376380214c1c977a479c6a9\", \"content_unittests\": \"91220293668c91068c21e7ac7d6c7d005df52a3d\", \"courgette_unittests\": \"a17f5074c9c443370901ebbd313a73fad4f2232d\", \"crashpad_tests\": \"2fa8caccdc442fd1d58e8af08fc7595ed23482c8\", \"cronet_tests\": \"bfbd5c7109b0219c3f2ace6094d81cf4e35db47c\", \"cronet_unittests\": \"c7b3d7a2e4fb1d52358f99a930487a4dc7135416\", \"crypto_unittests\": \"3da2dbc00740440fb318d4c7ff7565d884f2c7d4\", \"delayloads_unittests\": \"f17c4528c5557b2084622a62e0b652d83f8d379e\", \"device_unittests\": \"129dedba26302058d349e4ff0f7d1d1d58d9835c\", \"display_unittests\": \"3dded3e0c158770601b17cebdcaf6b7f769b89b1\", \"elevation_service_unittests\": \"d402a8cb432d0a9de418b6d8323c4535e99da3c7\", \"events_unittests\": \"7d665f42143be0484b340aacf7067d2d3b9e0a7f\", \"extensions_browsertests\": \"f76b84c4d3b6055e8c99b517a3536a4bb03e0ffa\", \"extensions_unittests\": \"4f3ce7eb35c47c74d3ea43b3297d7ce783705638\", \"filesystem_service_unittests\": \"9655ef6f16077ae45bdbc74a8f8f10c00a3374d7\", \"gcm_unit_tests\": \"98e7ab7a84ed03478dc5adbc1aa9e808da829e46\", \"gcp_unittests\": \"1aa692fdbcb83eccd099642084e9d23734c2d314\", \"gfx_unittests\": \"72b56fde9bbb6b2332132c4566d532c0df5fa8c2\", \"gin_unittests\": \"7383523fda3bb688113afedf8af1c930553a40b3\", \"google_apis_unittests\": \"8c28a7289f038282c806fec2b0c3a755ccbab2d4\", \"gpu_unittests\": \"d46f776717a574dc51151054f149a2565e8439ad\", \"gwp_asan_unittests\": \"d089adbe34f9eb3581284df92e7c9b9cae7bf80e\", \"headless_browsertests\": \"b73c08b7925c8565d0c972efee458cedcb367359\", \"headless_unittests\": \"4dbef0c1531da3e43969022f6a3d2f908c20d23c\", \"install_static_unittests\": \"7163b349bfd0c54ef4b121e2ffaf24d7ed9f6ab6\", \"installer_util_unittests\": \"7310a8eeb4c8a595c851cd502db4994edf4dd490\", \"interactive_ui_tests\": \"e2009a9c1a0b9dd0c9246b14d206cd80d25d96c0\", \"ipc_tests\": \"6d2dd05babf9a3ce3ba016c75a30e45925f79a78\", \"jingle_unittests\": \"7d8e708153c4dbff7f177847f8f80af63f79cc92\", \"latency_unittests\": \"77d3b937cfc985e00fe386baae8caf3b8351a45c\", \"libjingle_xmpp_unittests\": \"3a489152146b7b3b37723f41ce0858ae9ffdeef4\", \"liburlpattern_unittests\": \"4d9b160b32a730f6228271723d31ba3533ea8a90\", \"media_blink_unittests\": \"cd658e665150623e38ef30641c27a838df44238e\", \"media_unittests\": \"9b3ba2c3054e28cd8f51bc987b901f135e7083f2\", \"message_center_unittests\": \"9b41bcee29cc8b36fdf53995f691115ff183bfb9\", \"midi_unittests\": \"562be7788d8202af06b70df1cfa912093efd70d0\", \"mojo_core_unittests\": \"475f21aeb586ace23eee0c18ba22b4aafbea0bc3\", \"mojo_unittests\": \"930b7ed2f21b3e3e4c6bf786b10f52a831218ef7\", \"nacl_loader_unittests\": \"7dd8b1cdf09ab0baffcff6ce4f6a560b59b142b5\", \"native_theme_unittests\": \"f8d9823fa265e93c0e919fad10515d0fd7ee9a22\", \"net_unittests\": \"afe212f802637d81aaa532fde0194d720f042cf8\", \"notification_helper_unittests\": \"a59ec6af8ca0c2318f79a981c64d7530d55d86d6\", \"pdf_unittests\": \"5745e76a46ead8e5dc9bc36a62e0787bc2690e25\", \"perfetto_unittests\": \"82bd99ddee7719e34966774e38dc3b74958506d3\", \"ppapi_unittests\": \"d4d3f1a07ee120636c04868c67a11c73ffa6c676\", \"printing_unittests\": \"9575964cc9aacd054005ddf5e9563643a00e04a4\", \"remoting_unittests\": \"d0fcc50a7f5537e962548f502f6187ed1c91f17c\", \"sbox_integration_tests\": \"e6bd515c87268308824a2c219974ac6df2d152d1\", \"sbox_unittests\": \"fb25190ce879ae23856e8fa9ec2df388ea4a81f0\", \"sbox_validation_tests\": \"73711daf6e4bee6a49876d8236a80596d9026575\", \"service_manager_unittests\": \"535d61b9d7307e5651a0a30a45b8362771c0589b\", \"services_unittests\": \"d51797f692878e2c1c87aaa1929ba7d0ba755e1e\", \"setup_unittests\": \"5dcf7c9bf914e045e9c220fc75868b7a272cad19\", \"shell_dialogs_unittests\": \"207d905c177f70265b061047149b089cd984b9fa\", \"skia_unittests\": \"8393c59180568fd69c959ffc21f3e539ad1d409e\", \"snapshot_unittests\": \"8ae013c482a72f761b78a51313d7124f33051367\", \"sql_unittests\": \"b758c138cfee371b392954b82b108423636c39bc\", \"storage_unittests\": \"34ea398d4d53c5522975f0ae31a09a605c2696da\", \"sync_integration_tests\": \"95e2060e030ea29efa2db3e6d941ba522f51fc1a\", \"traffic_annotation_auditor_unittests\": \"e88349dc189c4c08d1655dae0cb550c2eb64f979\", \"ui_base_unittests\": \"f2a8ac4696e689ad69c5fedcc2b5813cdc86f50d\", \"ui_touch_selection_unittests\": \"f012ce055896fe72a4ac0e63c4428ac7d0e6554c\", \"unit_tests\": \"94af4c06d14bf2fc88f163b17ada7e6b2c9dc13e\", \"updater_tests\": \"f74880e0e06aed6c4cdc56f7edeeded77d8a1aa7\", \"url_unittests\": \"a4f7db51c1e0ef7b52358981ff91e587caf533a7\", \"views_unittests\": \"7fe050ee4737c96f004f924a1635ccda4327e579\", \"viz_unittests\": \"7e94b3b7a80e71a738465b4afe3a0e66fd2f8f98\", \"vr_common_unittests\": \"1a9470d72da7c0f2f71b97884553742c41c498c5\", \"vr_pixeltests\": \"4d41aba0e39a8af47adb060f1cc56ba0bbe4b04f\", \"weblayer_browsertests\": \"daa314bb2423ef9f1f12c74e9bcf6f4d2af516f5\", \"weblayer_unittests\": \"e00bfa38f6553c8737b107344920ece3d871b8bf\", \"wm_unittests\": \"31bdee8aa391b5caa4cc247b94a220ee67377df9\", \"wtf_unittests\": \"a16522ec00e03f0163eb3369aa152bf91baa8d13\", \"zlib_unittests\": \"ba6da342d7ae880f9c35d222d5c5daa0c2a982a3\", \"zucchini_unittests\": \"75335a4920051e8a5388fc68fa6c32da0ae47dce\"}}, \"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-3-xoby\"], \"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": "1605921837194485",
"status": "COMPLETED",
"status_changed_ts": "1605925878490885",
"tags": [
"build_address:luci.chromium.ci/win-asan/15750",
"builder:win-asan",
"buildset:commit/git/5cc24d18788ec6907336df45d34173f355bc5646",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cc24d18788ec6907336df45d34173f355bc5646",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028492686301982960",
"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/8863062623376955120/+/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:5001380e97f22010",
"user_agent:luci-scheduler"
],
"updated_ts": "1605925878496012",
"url": "https://ci.chromium.org/b/8863062623376955120",
"utcnow_ts": "1605925879578153"
}
}
[02:31:19.589] Invocation finished in 1h7m28.16977147s with status SUCCEEDED