[03:59:52.871] New invocation is queued and will start shortly
[03:59:53.960] Starting the invocation (attempt 1)
[03:59:54.000] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:59:54.000] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:59:54.000] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023500036977568784",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e330bc98d1335bf5035244a252f40e9efbe47db7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023500036977568784",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e330bc98d1335bf5035244a252f40e9efbe47db7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e330bc98d1335bf5035244a252f40e9efbe47db7",
"gitiles_ref:refs/heads/master"
]
}
[03:59:55.239] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1610683194145330",
"id": "8858069974974759184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e330bc98d1335bf5035244a252f40e9efbe47db7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610683194960302",
"tags": [
"build_address:luci.chromium.ci/win-asan/16829",
"builder:win-asan",
"buildset:commit/git/e330bc98d1335bf5035244a252f40e9efbe47db7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e330bc98d1335bf5035244a252f40e9efbe47db7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023500036977568784",
"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/8858069974974759184/+/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": "1610683194960443",
"url": "https://ci.chromium.org/b/8858069974974759184",
"utcnow_ts": "1610683195223456"
}
}
[03:59:55.239] Task URL: https://ci.chromium.org/b/8858069974974759184
[03:59:55.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:2:0) after 1m0s
[03:59:57.948] Received PubSub notification, asking Buildbucket for the build status
[03:59:58.017] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:55.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:2:0)
[04:00:55.384] Timer tick, asking Buildbucket for the build status
[04:00:55.734] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:55.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:4:0) after 1m0s
[04:01:55.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:4:0)
[04:01:55.734] Timer tick, asking Buildbucket for the build status
[04:01:55.894] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:55.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:5:0) after 1m0s
[04:02:55.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:5:0)
[04:02:55.964] Timer tick, asking Buildbucket for the build status
[04:02:57.847] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:57.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:6:0) after 1m0s
[04:03:57.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:6:0)
[04:03:57.907] Timer tick, asking Buildbucket for the build status
[04:03:58.028] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:58.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:7:0) after 1m0s
[04:04:58.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:7:0)
[04:04:58.022] Timer tick, asking Buildbucket for the build status
[04:04:58.124] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:58.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:8:0) after 1m0s
[04:05:58.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:8:0)
[04:05:58.145] Timer tick, asking Buildbucket for the build status
[04:05:58.291] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:58.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:9:0) after 1m0s
[04:06:58.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:9:0)
[04:06:58.320] Timer tick, asking Buildbucket for the build status
[04:06:58.559] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:58.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:10:0) after 1m0s
[04:07:58.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:10:0)
[04:07:58.582] Timer tick, asking Buildbucket for the build status
[04:07:58.740] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:58.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:11:0) after 1m0s
[04:08:58.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:11:0)
[04:08:58.797] Timer tick, asking Buildbucket for the build status
[04:08:58.905] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:58.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:12:0) after 1m0s
[04:09:58.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:12:0)
[04:09:58.917] Timer tick, asking Buildbucket for the build status
[04:09:59.139] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:59.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:13:0) after 1m0s
[04:10:59.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:13:0)
[04:10:59.198] Timer tick, asking Buildbucket for the build status
[04:10:59.327] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:59.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:14:0) after 1m0s
[04:11:59.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:14:0)
[04:11:59.350] Timer tick, asking Buildbucket for the build status
[04:11:59.452] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:59.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:15:0) after 1m0s
[04:12:59.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:15:0)
[04:12:59.479] Timer tick, asking Buildbucket for the build status
[04:12:59.593] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:59.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:16:0) after 1m0s
[04:13:59.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:16:0)
[04:13:59.641] Timer tick, asking Buildbucket for the build status
[04:13:59.743] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:59.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:17:0) after 1m0s
[04:14:59.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:17:0)
[04:14:59.821] Timer tick, asking Buildbucket for the build status
[04:14:59.931] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:59.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:18:0) after 1m0s
[04:15:59.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:18:0)
[04:15:59.953] Timer tick, asking Buildbucket for the build status
[04:16:03.182] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:03.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:19:0) after 1m0s
[04:17:03.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:19:0)
[04:17:03.264] Timer tick, asking Buildbucket for the build status
[04:17:03.504] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:03.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:20:0) after 1m0s
[04:18:03.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:20:0)
[04:18:03.535] Timer tick, asking Buildbucket for the build status
[04:18:03.651] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:03.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:21:0) after 1m0s
[04:19:03.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:21:0)
[04:19:03.682] Timer tick, asking Buildbucket for the build status
[04:19:03.912] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:03.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:22:0) after 1m0s
[04:20:03.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:22:0)
[04:20:03.985] Timer tick, asking Buildbucket for the build status
[04:20:04.159] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:04.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:23:0) after 1m0s
[04:21:04.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:23:0)
[04:21:04.156] Timer tick, asking Buildbucket for the build status
[04:21:04.330] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:04.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:24:0) after 1m0s
[04:22:04.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:24:0)
[04:22:04.351] Timer tick, asking Buildbucket for the build status
[04:22:04.464] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:04.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:25:0) after 1m0s
[04:23:04.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:25:0)
[04:23:04.509] Timer tick, asking Buildbucket for the build status
[04:23:04.587] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:04.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:26:0) after 1m0s
[04:24:04.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:26:0)
[04:24:04.620] Timer tick, asking Buildbucket for the build status
[04:24:04.773] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:04.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:27:0) after 1m0s
[04:25:04.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:27:0)
[04:25:04.840] Timer tick, asking Buildbucket for the build status
[04:25:04.980] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:04.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:28:0) after 1m0s
[04:26:05.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:28:0)
[04:26:05.315] Timer tick, asking Buildbucket for the build status
[04:26:05.460] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:05.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:29:0) after 1m0s
[04:27:05.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:29:0)
[04:27:05.581] Timer tick, asking Buildbucket for the build status
[04:27:06.593] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:06.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:30:0) after 1m0s
[04:28:06.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:30:0)
[04:28:06.643] Timer tick, asking Buildbucket for the build status
[04:28:06.786] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:06.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:31:0) after 1m0s
[04:29:06.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:31:0)
[04:29:06.860] Timer tick, asking Buildbucket for the build status
[04:29:07.022] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:07.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:32:0) after 1m0s
[04:30:07.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:32:0)
[04:30:07.064] Timer tick, asking Buildbucket for the build status
[04:30:07.216] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:07.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:33:0) after 1m0s
[04:31:07.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:33:0)
[04:31:07.432] Timer tick, asking Buildbucket for the build status
[04:31:07.525] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:07.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:34:0) after 1m0s
[04:32:07.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:34:0)
[04:32:07.615] Timer tick, asking Buildbucket for the build status
[04:32:07.698] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:07.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:35:0) after 1m0s
[04:33:07.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:35:0)
[04:33:07.780] Timer tick, asking Buildbucket for the build status
[04:33:08.338] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:08.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:36:0) after 1m0s
[04:34:08.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:36:0)
[04:34:08.345] Timer tick, asking Buildbucket for the build status
[04:34:08.419] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:08.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:37:0) after 1m0s
[04:35:08.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:37:0)
[04:35:08.521] Timer tick, asking Buildbucket for the build status
[04:35:09.092] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:38:0) after 1m0s
[04:36:09.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:38:0)
[04:36:09.298] Timer tick, asking Buildbucket for the build status
[04:36:09.389] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:09.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:39:0) after 1m0s
[04:37:09.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:39:0)
[04:37:09.401] Timer tick, asking Buildbucket for the build status
[04:37:09.480] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:09.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:40:0) after 1m0s
[04:38:09.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:40:0)
[04:38:09.575] Timer tick, asking Buildbucket for the build status
[04:38:09.981] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:09.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:41:0) after 1m0s
[04:39:10.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:41:0)
[04:39:10.004] Timer tick, asking Buildbucket for the build status
[04:39:10.182] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:10.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:42:0) after 1m0s
[04:40:10.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:42:0)
[04:40:10.234] Timer tick, asking Buildbucket for the build status
[04:40:10.599] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:10.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:43:0) after 1m0s
[04:41:10.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:43:0)
[04:41:10.697] Timer tick, asking Buildbucket for the build status
[04:41:10.938] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:10.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:44:0) after 1m0s
[04:42:10.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:44:0)
[04:42:10.972] Timer tick, asking Buildbucket for the build status
[04:42:11.507] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:11.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:45:0) after 1m0s
[04:43:11.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:45:0)
[04:43:11.614] Timer tick, asking Buildbucket for the build status
[04:43:11.732] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:11.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:46:0) after 1m0s
[04:44:11.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:46:0)
[04:44:11.756] Timer tick, asking Buildbucket for the build status
[04:44:12.117] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:12.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:47:0) after 1m0s
[04:45:12.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:47:0)
[04:45:12.162] Timer tick, asking Buildbucket for the build status
[04:45:12.278] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:12.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:48:0) after 1m0s
[04:46:12.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:48:0)
[04:46:12.312] Timer tick, asking Buildbucket for the build status
[04:46:12.618] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:12.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:49:0) after 1m0s
[04:47:12.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:49:0)
[04:47:12.706] Timer tick, asking Buildbucket for the build status
[04:47:12.775] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:12.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:50:0) after 1m0s
[04:48:12.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:50:0)
[04:48:12.799] Timer tick, asking Buildbucket for the build status
[04:48:12.905] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:51:0) after 1m0s
[04:49:12.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:51:0)
[04:49:12.945] Timer tick, asking Buildbucket for the build status
[04:49:13.061] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:13.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:52:0) after 1m0s
[04:50:13.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:52:0)
[04:50:13.137] Timer tick, asking Buildbucket for the build status
[04:50:13.258] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:13.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:53:0) after 1m0s
[04:51:13.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:53:0)
[04:51:13.306] Timer tick, asking Buildbucket for the build status
[04:51:13.371] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:13.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:54:0) after 1m0s
[04:52:13.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:54:0)
[04:52:13.364] Timer tick, asking Buildbucket for the build status
[04:52:13.616] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:13.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:55:0) after 1m0s
[04:53:13.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:55:0)
[04:53:13.620] Timer tick, asking Buildbucket for the build status
[04:53:13.767] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:56:0) after 1m0s
[04:54:13.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:56:0)
[04:54:13.801] Timer tick, asking Buildbucket for the build status
[04:54:13.936] Build 8858069974974759184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:13.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023500036977568784:57:0) after 1m0s
[04:54:59.036] Received PubSub notification, asking Buildbucket for the build status
[04:54:59.164] Build 8858069974974759184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:54:59.164] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1610686498056252",
"created_by": "project:chromium",
"created_ts": "1610683194145330",
"id": "8858069974974759184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e330bc98d1335bf5035244a252f40e9efbe47db7\"}}",
"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\": \"e330bc98d1335bf5035244a252f40e9efbe47db7\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16829, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"c07ef602741b3b051ac4823b5a74327279ffc68b\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"e330bc98d1335bf5035244a252f40e9efbe47db7\", \"got_revision_cp\": \"refs/heads/master@{#843933}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"e330bc98d1335bf5035244a252f40e9efbe47db7\", \"swarm_hashes_refs/heads/master(at){#843933}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"6117fe229611123e8746b02fe51d0424a4626dbe\", \"angle_unittests\": \"3c5f0cd7e5670dc1274caf17011defee43fb7fc8\", \"app_shell_unittests\": \"2a534306c3c6475617bc93603fc7e1a2ea5515c3\", \"aura_unittests\": \"7ce9f943681597dedfb34ae9dcae25a7db64d653\", \"base_unittests\": \"8174246b5bfa345a5bf3ac78ce8a496740fb252f\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"590730a0eb903fe4852c97373af791764ade9ae7\", \"blink_fuzzer_unittests\": \"29a856eb9041966dd1b9895db5353b91c40b4927\", \"blink_heap_unittests\": \"79b4d08da1bd38f0493e89b2bf1c2fffbe889c19\", \"blink_platform_unittests\": \"271ba7086cde1a18699e2abd389422cb2011ce21\", \"blink_unittests\": \"fa7ba0aacfa92442736b726f5cba4cdeffbac94e\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"0b20d45386bedfb07f7ad7c73617a74a6e8df740\", \"capture_unittests\": \"906a38517f2e63be8c49e297815cb8129effdbe2\", \"cast_unittests\": \"98d0696fe4c9939375dc4edf4b8c62f07823c9c4\", \"cc_unittests\": \"4643106592247d04271249db461f76d4dbfb9876\", \"chrome_app_unittests\": \"886899793141f043fb72d81893401df28005cc5d\", \"chrome_cleaner_unittests\": \"8a1a2452b00d2400670971575a7407687769c99b\", \"chrome_elf_unittests\": \"22b13179ba259d26cf153d7607542de0ba1c6a57\", \"chromedriver_unittests\": \"0645d575d777fb06cc2c90b639a2f17960c6ffc5\", \"components_browsertests\": \"5e1f4ae2d2c29ae96ca231161d59661b44295a12\", \"components_unittests\": \"0e34af539bca71f6bd25aef0b486ba28dd9f2e94\", \"compositor_unittests\": \"d1e7c5ae45fbf9ac4f26418fcb6a62d27c5876f1\", \"content_browsertests\": \"c499bad88329b8cfc1c0f0015fed5ed1dec4db8e\", \"content_unittests\": \"f24a20a330296180a4782b8171c00c6a3b1a3cdf\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"7a60ea3d0672ecaa9fb73adbcec942c8f520828d\", \"cronet_unittests\": \"962b4e2c753483d011a6d3b9f33020a507732ac7\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"4351f93264a8a4c44571a0033a58b08d316ed56d\", \"device_unittests\": \"dde5b84cdc8f8fcbeca611aa65892b3f8ad6da45\", \"display_unittests\": \"9c722805aed7d61e93a26f68502fbdbe8199b455\", \"elevation_service_unittests\": \"569787f16f1c8e0d7eade23b605e6498f48d0ea3\", \"events_unittests\": \"d2e320a5cd46b4a4d54f8caff9e03b9d9ee174af\", \"extensions_browsertests\": \"83a82ee73258e6ebd259c8165d1f124b4d205499\", \"extensions_unittests\": \"944431fd744c8494d77195fec1ba0c9b60764341\", \"filesystem_service_unittests\": \"de1375391a82c32086a9b940a42c70bb7f487603\", \"gcm_unit_tests\": \"ef9afff71849596576df90ba10072abde7f60b3e\", \"gcp_unittests\": \"82680d7d8d91428d9369f71e242b46933d97f757\", \"gfx_unittests\": \"d8924caee36853257f2d294589a30c0d54167a3d\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"d2dee37e2ca2bd758ee5fac59308f7bb3d4d0d3f\", \"gpu_unittests\": \"a54b77b7e4f7fa997e26f3078638140a0d41192d\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"641b262428e478f62bc05ae703d0d3d505e866b1\", \"headless_unittests\": \"0490e5ad02aa66bbdb021a7dd82a1b618ac01bd6\", \"install_static_unittests\": \"ac025ac6cc9fb66bcf7e448b1a59f5065ccac99b\", \"installer_util_unittests\": \"fb8d9594f130ca50c1674397ad38614100bed2d2\", \"interactive_ui_tests\": \"fb6791ce27d31cf14d3cf19ce01a9ae39cc01d83\", \"ipc_tests\": \"b12d46fb37b339de67b3f3466e092cb9bbef67e0\", \"jingle_unittests\": \"8ad440532c8bd2e7c21dc3a093937e6fedeee333\", \"latency_unittests\": \"a6504f5cd52fd2eab064b660608ee7c60417f43a\", \"libjingle_xmpp_unittests\": \"195c09bcc503e019af953742f15d87e622df0ee4\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"9b6614587b4737146ad45fa2b9da4db694178f58\", \"media_unittests\": \"e04fb56badf973df7c52b8a9ab0abceea7a15191\", \"message_center_unittests\": \"424559c13fa57b05efcf4d774600e73834f43c57\", \"midi_unittests\": \"71bacbad1282ac41ad256c87022cf5a4ac442738\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"08293221a506fe3f918a0022516652622f2971de\", \"nacl_loader_unittests\": \"4e734a3d15cb270373758f36e48b6ad851b8f897\", \"native_theme_unittests\": \"f8410a02e57799b42676af6b4f3df38cec17709d\", \"net_unittests\": \"a399dd2d37a063a87dc1fb0ed51cdc7c8914d67b\", \"notification_helper_unittests\": \"ec272939fd26d391b0e34f9aa887d2553d333592\", \"pdf_unittests\": \"85520848832b21903a3e4da0f6df0da5dbdc96ba\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"3b8566de01e4871d5e6cfdaf129cdd16d422388c\", \"printing_unittests\": \"f9f9be199d98a99dac76540bdf7bd52a5f394157\", \"remoting_unittests\": \"2613f13fde33d8b99b2d1be24ecb8ffd454531f1\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"8a50eb22cdec5a483ab437a7b18fdcb19296ff1f\", \"services_unittests\": \"911e68e9f54baaf871da1fc2288b9a35ca4c1ed5\", \"setup_unittests\": \"bf3ed7345828558820465d6c5d8cc16341b83a10\", \"shell_dialogs_unittests\": \"b3d9ad2a28751ed324794c27a315010f970e9927\", \"skia_unittests\": \"819b1ea362c95a27279001f2bee92b9bd1eb2aee\", \"snapshot_unittests\": \"bf82b84b49c759ae7a8ec4a6b31b857da397adc0\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"a55a2d7b44d7c6d51902fe6b194ac3367812d993\", \"sync_integration_tests\": \"a804a9dfc9bcbcf4670b8d07cd02a99620a22e62\", \"traffic_annotation_auditor_unittests\": \"8c8a787fe47b243c058062bfae9decec2050e5ec\", \"ui_base_unittests\": \"44ddda1735836adc355f9b4d3cf102f2572c53b8\", \"ui_touch_selection_unittests\": \"a7567e763811d8f5b2e6ee80bcb038e72e9437c0\", \"unit_tests\": \"a57664f75b1104b2ac929566f45786680f4867e4\", \"updater_tests\": \"764bef456d32cd3c5e62d0302abe0d449fd6ddc0\", \"url_unittests\": \"92fbc6978ab0481ad3caedd825b58a5b424df16a\", \"views_unittests\": \"052a936dd843876472b685fd87fbe75e1423b284\", \"viz_unittests\": \"e95d63cd951856b3be5d60cd0ac99d6d8f3b6a2e\", \"vr_common_unittests\": \"4674724ba2c1d40744f230b7163422bfa52c587b\", \"vr_pixeltests\": \"bbcec82840104e1a6ddb2a8583b47b9356117976\", \"weblayer_browsertests\": \"3bf2f78b874eb5740f178771f1734527873e5d50\", \"weblayer_unittests\": \"d01a858b1135ad3f376f53a6ccc08eb5a4b2d776\", \"wm_unittests\": \"bfd42cd3aaac99908bef19b928c016b9930bc4c0\", \"wtf_unittests\": \"f99fcc30c36a716795d4e03a66c29e62a631a0aa\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-nb2x\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"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": "1610683197408143",
"status": "COMPLETED",
"status_changed_ts": "1610686498346250",
"tags": [
"build_address:luci.chromium.ci/win-asan/16829",
"builder:win-asan",
"buildset:commit/git/e330bc98d1335bf5035244a252f40e9efbe47db7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e330bc98d1335bf5035244a252f40e9efbe47db7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023500036977568784",
"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/8858069974974759184/+/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:511d04a8e5142e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610686498350855",
"url": "https://ci.chromium.org/b/8858069974974759184",
"utcnow_ts": "1610686499147572"
}
}
[04:54:59.164] Invocation finished in 55m6.313249913s with status SUCCEEDED