[04:02:56.770] New invocation is queued and will start shortly
[04:02:57.931] Starting the invocation (attempt 1)
[04:02:57.962] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:02:57.963] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:02:57.963] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023952828973362144",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1e48b3413134e4becd27e9b4107d335690cc9aa8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023952828973362144",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"gitiles_ref:refs/heads/master"
]
}
[04:02:58.989] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610251378081095",
"id": "8858522766935391520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1e48b3413134e4becd27e9b4107d335690cc9aa8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610251378805720",
"tags": [
"build_address:luci.chromium.ci/win-asan/16727",
"builder:win-asan",
"buildset:commit/git/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023952828973362144",
"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/8858522766935391520/+/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": "1610251378805992",
"url": "https://ci.chromium.org/b/8858522766935391520",
"utcnow_ts": "1610251378975996"
}
}
[04:02:58.989] Task URL: https://ci.chromium.org/b/8858522766935391520
[04:02:58.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:2:0) after 1m0s
[04:03:06.145] Received PubSub notification, asking Buildbucket for the build status
[04:03:06.197] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:59.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:2:0)
[04:03:59.170] Timer tick, asking Buildbucket for the build status
[04:03:59.239] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:59.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:4:0) after 1m0s
[04:04:59.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:4:0)
[04:04:59.312] Timer tick, asking Buildbucket for the build status
[04:04:59.430] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:59.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:5:0) after 1m0s
[04:05:59.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:5:0)
[04:05:59.475] Timer tick, asking Buildbucket for the build status
[04:05:59.580] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:59.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:6:0) after 1m0s
[04:06:59.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:6:0)
[04:06:59.619] Timer tick, asking Buildbucket for the build status
[04:06:59.698] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:59.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:7:0) after 1m0s
[04:07:59.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:7:0)
[04:07:59.732] Timer tick, asking Buildbucket for the build status
[04:07:59.829] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:59.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:8:0) after 1m0s
[04:08:59.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:8:0)
[04:08:59.915] Timer tick, asking Buildbucket for the build status
[04:09:00.135] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:00.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:9:0) after 1m0s
[04:10:00.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:9:0)
[04:10:00.546] Timer tick, asking Buildbucket for the build status
[04:10:00.789] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:00.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:10:0) after 1m0s
[04:11:01.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:10:0)
[04:11:01.221] Timer tick, asking Buildbucket for the build status
[04:11:01.319] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:01.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:11:0) after 1m0s
[04:12:02.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:11:0)
[04:12:02.006] Timer tick, asking Buildbucket for the build status
[04:12:02.127] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:02.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:12:0) after 1m0s
[04:13:02.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:12:0)
[04:13:02.193] Timer tick, asking Buildbucket for the build status
[04:13:02.488] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:02.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:13:0) after 1m0s
[04:14:03.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:13:0)
[04:14:03.256] Timer tick, asking Buildbucket for the build status
[04:14:03.419] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:03.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:14:0) after 1m0s
[04:15:03.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:14:0)
[04:15:03.439] Timer tick, asking Buildbucket for the build status
[04:15:03.519] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:03.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:15:0) after 1m0s
[04:16:03.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:15:0)
[04:16:03.605] Timer tick, asking Buildbucket for the build status
[04:16:03.934] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:03.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:16:0) after 1m0s
[04:17:03.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:16:0)
[04:17:03.988] Timer tick, asking Buildbucket for the build status
[04:17:04.103] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:04.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:17:0) after 1m0s
[04:18:04.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:17:0)
[04:18:04.205] Timer tick, asking Buildbucket for the build status
[04:18:04.350] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:04.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:18:0) after 1m0s
[04:19:04.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:18:0)
[04:19:04.326] Timer tick, asking Buildbucket for the build status
[04:19:04.473] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:04.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:19:0) after 1m0s
[04:20:04.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:19:0)
[04:20:04.552] Timer tick, asking Buildbucket for the build status
[04:20:04.713] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:04.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:20:0) after 1m0s
[04:21:04.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:20:0)
[04:21:04.865] Timer tick, asking Buildbucket for the build status
[04:21:05.504] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:05.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:21:0) after 1m0s
[04:22:05.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:21:0)
[04:22:05.606] Timer tick, asking Buildbucket for the build status
[04:22:05.873] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:05.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:22:0) after 1m0s
[04:23:05.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:22:0)
[04:23:05.909] Timer tick, asking Buildbucket for the build status
[04:23:06.005] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:06.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:23:0) after 1m0s
[04:24:06.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:23:0)
[04:24:06.128] Timer tick, asking Buildbucket for the build status
[04:24:06.789] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:06.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:24:0) after 1m0s
[04:25:06.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:24:0)
[04:25:06.803] Timer tick, asking Buildbucket for the build status
[04:25:06.931] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:06.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:25:0) after 1m0s
[04:26:06.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:25:0)
[04:26:06.973] Timer tick, asking Buildbucket for the build status
[04:26:07.134] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:07.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:26:0) after 1m0s
[04:27:07.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:26:0)
[04:27:07.180] Timer tick, asking Buildbucket for the build status
[04:27:07.253] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:07.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:27:0) after 1m0s
[04:28:07.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:27:0)
[04:28:07.290] Timer tick, asking Buildbucket for the build status
[04:28:07.460] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:07.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:28:0) after 1m0s
[04:29:07.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:28:0)
[04:29:07.597] Timer tick, asking Buildbucket for the build status
[04:29:07.904] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:07.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:29:0) after 1m0s
[04:30:07.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:29:0)
[04:30:07.954] Timer tick, asking Buildbucket for the build status
[04:30:08.056] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:08.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:30:0) after 1m0s
[04:31:08.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:30:0)
[04:31:08.124] Timer tick, asking Buildbucket for the build status
[04:31:08.188] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:08.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:31:0) after 1m0s
[04:32:08.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:31:0)
[04:32:08.273] Timer tick, asking Buildbucket for the build status
[04:32:08.352] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:08.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:32:0) after 1m0s
[04:33:08.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:32:0)
[04:33:08.390] Timer tick, asking Buildbucket for the build status
[04:33:08.492] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:08.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:33:0) after 1m0s
[04:34:08.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:33:0)
[04:34:08.515] Timer tick, asking Buildbucket for the build status
[04:34:08.755] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:08.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:34:0) after 1m0s
[04:35:08.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:34:0)
[04:35:08.817] Timer tick, asking Buildbucket for the build status
[04:35:08.885] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:08.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:35:0) after 1m0s
[04:36:08.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:35:0)
[04:36:08.911] Timer tick, asking Buildbucket for the build status
[04:36:09.055] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:09.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:36:0) after 1m0s
[04:37:09.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:36:0)
[04:37:09.109] Timer tick, asking Buildbucket for the build status
[04:37:09.293] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:09.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:37:0) after 1m0s
[04:38:09.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:37:0)
[04:38:09.388] Timer tick, asking Buildbucket for the build status
[04:38:09.505] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:09.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:38:0) after 1m0s
[04:39:09.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:38:0)
[04:39:09.546] Timer tick, asking Buildbucket for the build status
[04:39:09.646] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:39:0) after 1m0s
[04:40:09.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:39:0)
[04:40:09.730] Timer tick, asking Buildbucket for the build status
[04:40:10.075] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:10.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:40:0) after 1m0s
[04:41:10.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:40:0)
[04:41:10.142] Timer tick, asking Buildbucket for the build status
[04:41:10.334] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:41:0) after 1m0s
[04:42:10.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:41:0)
[04:42:10.418] Timer tick, asking Buildbucket for the build status
[04:42:10.777] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:10.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:42:0) after 1m0s
[04:43:10.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:42:0)
[04:43:10.797] Timer tick, asking Buildbucket for the build status
[04:43:10.947] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:10.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:43:0) after 1m0s
[04:44:10.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:43:0)
[04:44:10.969] Timer tick, asking Buildbucket for the build status
[04:44:11.377] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:11.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:44:0) after 1m0s
[04:45:11.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:44:0)
[04:45:11.394] Timer tick, asking Buildbucket for the build status
[04:45:11.518] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:11.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:45:0) after 1m0s
[04:46:11.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:45:0)
[04:46:11.542] Timer tick, asking Buildbucket for the build status
[04:46:11.636] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:11.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:46:0) after 1m0s
[04:47:11.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:46:0)
[04:47:11.640] Timer tick, asking Buildbucket for the build status
[04:47:11.934] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:11.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:47:0) after 1m0s
[04:48:11.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:47:0)
[04:48:11.994] Timer tick, asking Buildbucket for the build status
[04:48:12.092] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:48:0) after 1m0s
[04:49:12.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:48:0)
[04:49:12.236] Timer tick, asking Buildbucket for the build status
[04:49:12.393] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:12.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:49:0) after 1m0s
[04:50:12.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:49:0)
[04:50:12.406] Timer tick, asking Buildbucket for the build status
[04:50:12.489] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:12.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:50:0) after 1m0s
[04:51:12.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:50:0)
[04:51:12.550] Timer tick, asking Buildbucket for the build status
[04:51:12.635] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:12.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:51:0) after 1m0s
[04:52:12.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:51:0)
[04:52:12.689] Timer tick, asking Buildbucket for the build status
[04:52:12.907] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:12.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:52:0) after 1m0s
[04:53:12.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:52:0)
[04:53:12.907] Timer tick, asking Buildbucket for the build status
[04:53:13.919] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:53:0) after 1m0s
[04:54:13.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:53:0)
[04:54:13.938] Timer tick, asking Buildbucket for the build status
[04:54:14.049] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:14.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:54:0) after 1m0s
[04:55:14.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:54:0)
[04:55:14.146] Timer tick, asking Buildbucket for the build status
[04:55:14.250] Build 8858522766935391520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:14.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023952828973362144:55:0) after 1m0s
[04:55:28.882] Received PubSub notification, asking Buildbucket for the build status
[04:55:28.952] Build 8858522766935391520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:55:28.952] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610254527815420",
"created_by": "project:chromium",
"created_ts": "1610251378081095",
"id": "8858522766935391520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1e48b3413134e4becd27e9b4107d335690cc9aa8\"}}",
"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\": \"1e48b3413134e4becd27e9b4107d335690cc9aa8\", \"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-0-unho\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16727, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"1e48b3413134e4becd27e9b4107d335690cc9aa8\", \"got_revision_cp\": \"refs/heads/master@{#841858}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"588d32cb3f172d1a6092c2b3c7e5a5c5d8b3b06e\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"42082f9045e74e65b9e3a66b51093e0dc2f9375a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32929}\", \"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\": \"1e48b3413134e4becd27e9b4107d335690cc9aa8\", \"swarm_hashes_refs/heads/master(at){#841858}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"a568675c9a044f49bcc5137dfdf5a67a57ae0f87\", \"angle_unittests\": \"967aaa143f5445e0c57b36e63b9860b8693b0e2c\", \"app_shell_unittests\": \"29c35253626c7cb3fbbc8e1a14a4106c9dbc06b6\", \"aura_unittests\": \"7d291d77980f95529904136aa7bbe817fdef355a\", \"base_unittests\": \"918d47eab7f5258d7f810806a622148e4af87a6f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"71d749707b1ce581de38a0181ae4bc9c2d967196\", \"blink_fuzzer_unittests\": \"f15db8dddd666d2d68ac3528a2596800bb785d5c\", \"blink_heap_unittests\": \"1e26974e4187d0119839f31f6616da354f140b4f\", \"blink_platform_unittests\": \"b5025bba9c53b9706345baaebe7f2375433f34ae\", \"blink_unittests\": \"c17b62110f9d4965e4905fcf7252c36afab1091c\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"882aa0af94cedc05f778d6cd9c953edbbaba35cd\", \"capture_unittests\": \"2bb0dc6508cfab0af389fab652de372338c66810\", \"cast_unittests\": \"1f9901b769f89be87ec442daa5d792ee5601f08c\", \"cc_unittests\": \"957b82595ac19a743fa400e26e9a632dfab8dab7\", \"chrome_app_unittests\": \"3e1586339e95c32d4e55247c2a12968055e92751\", \"chrome_cleaner_unittests\": \"2f3f4396c6173a4726b65f67ecf9b2fde029aa04\", \"chrome_elf_unittests\": \"5a89c934be15a4ac2d603c66fe49e47639a49939\", \"chromedriver_unittests\": \"b206dc3e44b1688b005c11bc7ac9e0437f7ac846\", \"components_browsertests\": \"a27e87528645405915a6d20be130105bc561a071\", \"components_unittests\": \"20dfd31a5181086ce24268f8d18daf6e9135c75f\", \"compositor_unittests\": \"97ac13c4a4e174561405b131a2bab2c40653f419\", \"content_browsertests\": \"52f3d3755f5456726c09db5bdd6ffd47c1817a79\", \"content_unittests\": \"f320e42eb912e0c02f39f08e292d7da01db38f6f\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"17033127b91003f6052ab71c76d328832d7c9235\", \"cronet_unittests\": \"b279c514006e14f2de5ac51238941d7e81a589f0\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"0df3dd96cee72196246d0ce2329de93f2d9e6bd0\", \"device_unittests\": \"e4bfbbebf750378d456ea85ee6a382ef07177f20\", \"display_unittests\": \"2134732173fed45ae047bb03a28c77449a9d6ba4\", \"elevation_service_unittests\": \"0f5490985b498018cb6ab635a71a009287b5553e\", \"events_unittests\": \"a0ed31e6a0bc5eb963dfeac95fc1c88b19bce360\", \"extensions_browsertests\": \"005b0308e940cfd8e3b07a07e04ccee574e3bed9\", \"extensions_unittests\": \"50c158d569af40d19acda25a55f7a73eada3ccb2\", \"filesystem_service_unittests\": \"9cfa7901e0643be210200ed7d40e6c3473fa2ead\", \"gcm_unit_tests\": \"abd30649b035df4fba83d003c40c7cae7785336a\", \"gcp_unittests\": \"570faca7ffbb4414d4a6c80a30807fdce949be1b\", \"gfx_unittests\": \"08e1be5db082745821f4f8e73841d3f9e5e9389b\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"fb46f508490b7ecaf4fb6ceebe4076bf3b5f759a\", \"gpu_unittests\": \"fba51fa74ded87aede29177ffab8085f11c79ecf\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"c3b840d7573b5210a1c393aaca358cc955703bf7\", \"headless_unittests\": \"45f19dd5665728e14cdd6eed8a466c86317cf852\", \"install_static_unittests\": \"efa57f838e92260c51c4b6c7159f305f356ca5e1\", \"installer_util_unittests\": \"93f458213dae26774fcbea1b7f4524a0a6a13f21\", \"interactive_ui_tests\": \"e8e60ad0fbab57205f9ac061a0b816fb89a8c4d1\", \"ipc_tests\": \"4589f13dffa7cd8624ce109086fac1fc6bfd68b3\", \"jingle_unittests\": \"985643f4baf2b23cb4fb5875cabb0bb08d0503a9\", \"latency_unittests\": \"fc166fc25593224a940a444c6b036f4f29688bfd\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"aded27f50bb7af559c1c118e76148581a106d2ce\", \"media_unittests\": \"3a69916adbd428ba394453be1532bef485c45c1b\", \"message_center_unittests\": \"e86f4ed57f4731f8b93c61f90dd171588109caa0\", \"midi_unittests\": \"e86bf65b73995d989d0a8e579796fbd2582d585c\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"1d9c955796241e4455c4dd5611319c1ea3109e0c\", \"nacl_loader_unittests\": \"3a2ef1be1c13aef87e561e737ea7d09d6e8a9bf9\", \"native_theme_unittests\": \"80439f2901c27618ce3f174dafec9dd7d84e5e32\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"97a97bad21e945f6ea582d36c96dc911f49227ac\", \"pdf_unittests\": \"169dd0d38d3df49c6b803305b9244f6e29219aaa\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"9b6575c2cc1a9c741e4dd2d4d320e4eb3360b426\", \"printing_unittests\": \"c540430c78e8c72fb62146bb4d0955ad0f875090\", \"remoting_unittests\": \"21aea6181cada426a2cedb8e05209309ebc83003\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"af3274c6fbb1a3d05375e6cb12dd512e07d45e25\", \"services_unittests\": \"68dcd757eb8c200334ae02aefe7f7ebd940988f8\", \"setup_unittests\": \"64dcf91a7d8854e2ec86daf29755e285856d0cf4\", \"shell_dialogs_unittests\": \"ea114040a4fba3807c96dadc4dd8d78dbf950b98\", \"skia_unittests\": \"256de3c9ae35cbd2eb57efc7b2d0569ab6bcf3e5\", \"snapshot_unittests\": \"6b3f50336c9dd4869ad65255aae0135e323ecfc6\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"02dc1a8568a6ff8820fdbc81176f09d2fb3d919e\", \"sync_integration_tests\": \"ae2bd474c285161ca72945ad3ac124385b7f0cd8\", \"traffic_annotation_auditor_unittests\": \"bfcc6dd7d1d2d85f15b891ceb16cc2943933f50b\", \"ui_base_unittests\": \"8d4ab4143fa25f33c55ec08db601c4db7a8320ca\", \"ui_touch_selection_unittests\": \"5d6712ff60de8998c15a02e2164465ca94245b2b\", \"unit_tests\": \"efd82bf931a348e74b960ff351d511285e95c5e3\", \"updater_tests\": \"34baa47fd329feda7889b40714484ede3a2904b4\", \"url_unittests\": \"7ab10902c9980a2aefc4ef02d4d72c1f12468add\", \"views_unittests\": \"69596a20b4b5745683c21e92116b54156e09340d\", \"viz_unittests\": \"4d8c8d9d3837ee3ea3d28d4b3fa6f53e240c63ec\", \"vr_common_unittests\": \"e6309b0da3d018f60e764bed848df9bb93cfb389\", \"vr_pixeltests\": \"92e0a6400017c0e1b390841712151b24fc6b594b\", \"weblayer_browsertests\": \"af7bb9fe11ff9e1efeb528f6c52c3c5412f1f3ae\", \"weblayer_unittests\": \"649231f0673469614be586b3ade7c920f3f1bc52\", \"wm_unittests\": \"b20a85e023190eb9040ef60b7a9f91c0d6172929\", \"wtf_unittests\": \"47b5120d1142e4b629b0d26b58369702dea78aaf\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-0-unho\"], \"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": "1610251385431709",
"status": "COMPLETED",
"status_changed_ts": "1610254528230408",
"tags": [
"build_address:luci.chromium.ci/win-asan/16727",
"builder:win-asan",
"buildset:commit/git/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e48b3413134e4becd27e9b4107d335690cc9aa8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023952828973362144",
"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/8858522766935391520/+/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:510347bcddf5f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610254528234902",
"url": "https://ci.chromium.org/b/8858522766935391520",
"utcnow_ts": "1610254528943573"
}
}
[04:55:28.952] Invocation finished in 52m32.197637507s with status SUCCEEDED