[12:16:08.902] New invocation is queued and will start shortly
[12:16:10.308] Starting the invocation (attempt 1)
[12:16:10.349] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:16:10.349] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:16:10.350] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027545678031523504",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d5863b7e317f06f595f06ce54d33b0312ef88137\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027545678031523504",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d5863b7e317f06f595f06ce54d33b0312ef88137",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5863b7e317f06f595f06ce54d33b0312ef88137",
"gitiles_ref:refs/heads/master"
]
}
[12:16:11.225] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1606824970440295",
"id": "8862115615754407232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5863b7e317f06f595f06ce54d33b0312ef88137\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606824971006141",
"tags": [
"build_address:luci.chromium.ci/win-asan/15967",
"builder:win-asan",
"buildset:commit/git/d5863b7e317f06f595f06ce54d33b0312ef88137",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5863b7e317f06f595f06ce54d33b0312ef88137",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027545678031523504",
"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/8862115615754407232/+/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": "1606824971006290",
"url": "https://ci.chromium.org/b/8862115615754407232",
"utcnow_ts": "1606824971218337"
}
}
[12:16:11.225] Task URL: https://ci.chromium.org/b/8862115615754407232
[12:16:11.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:2:0) after 1m0s
[12:16:24.747] Received PubSub notification, asking Buildbucket for the build status
[12:16:24.838] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:2:0)
[12:17:11.377] Timer tick, asking Buildbucket for the build status
[12:17:11.451] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:4:0) after 1m0s
[12:18:11.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:4:0)
[12:18:11.455] Timer tick, asking Buildbucket for the build status
[12:18:11.984] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:11.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:5:0) after 1m0s
[12:19:11.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:5:0)
[12:19:11.982] Timer tick, asking Buildbucket for the build status
[12:19:12.284] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:12.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:6:0) after 1m0s
[12:20:12.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:6:0)
[12:20:12.342] Timer tick, asking Buildbucket for the build status
[12:20:12.460] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:12.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:7:0) after 1m0s
[12:21:12.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:7:0)
[12:21:12.526] Timer tick, asking Buildbucket for the build status
[12:21:12.660] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:12.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:8:0) after 1m0s
[12:22:12.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:8:0)
[12:22:12.747] Timer tick, asking Buildbucket for the build status
[12:22:12.881] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:12.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:9:0) after 1m0s
[12:23:13.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:9:0)
[12:23:13.123] Timer tick, asking Buildbucket for the build status
[12:23:13.212] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:13.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:10:0) after 1m0s
[12:24:13.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:10:0)
[12:24:13.289] Timer tick, asking Buildbucket for the build status
[12:24:13.373] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:13.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:11:0) after 1m0s
[12:25:13.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:11:0)
[12:25:13.531] Timer tick, asking Buildbucket for the build status
[12:25:14.103] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:14.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:12:0) after 1m0s
[12:26:14.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:12:0)
[12:26:14.177] Timer tick, asking Buildbucket for the build status
[12:26:16.057] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:16.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:13:0) after 1m0s
[12:27:16.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:13:0)
[12:27:16.429] Timer tick, asking Buildbucket for the build status
[12:27:16.546] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:16.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:14:0) after 1m0s
[12:28:16.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:14:0)
[12:28:16.678] Timer tick, asking Buildbucket for the build status
[12:28:16.740] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:16.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:15:0) after 1m0s
[12:29:16.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:15:0)
[12:29:16.903] Timer tick, asking Buildbucket for the build status
[12:29:16.971] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:16.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:16:0) after 1m0s
[12:30:17.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:16:0)
[12:30:17.085] Timer tick, asking Buildbucket for the build status
[12:30:17.168] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:17.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:17:0) after 1m0s
[12:31:17.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:17:0)
[12:31:17.454] Timer tick, asking Buildbucket for the build status
[12:31:17.517] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:17.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:18:0) after 1m0s
[12:32:17.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:18:0)
[12:32:17.508] Timer tick, asking Buildbucket for the build status
[12:32:17.609] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:17.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:19:0) after 1m0s
[12:33:17.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:19:0)
[12:33:17.698] Timer tick, asking Buildbucket for the build status
[12:33:17.859] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:17.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:20:0) after 1m0s
[12:34:17.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:20:0)
[12:34:17.844] Timer tick, asking Buildbucket for the build status
[12:34:17.964] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:17.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:21:0) after 1m0s
[12:35:18.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:21:0)
[12:35:18.051] Timer tick, asking Buildbucket for the build status
[12:35:18.350] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:18.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:22:0) after 1m0s
[12:36:18.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:22:0)
[12:36:18.410] Timer tick, asking Buildbucket for the build status
[12:36:18.609] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:18.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:23:0) after 1m0s
[12:37:18.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:23:0)
[12:37:18.624] Timer tick, asking Buildbucket for the build status
[12:37:18.861] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:18.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:24:0) after 1m0s
[12:38:18.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:24:0)
[12:38:18.946] Timer tick, asking Buildbucket for the build status
[12:38:19.103] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:19.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:25:0) after 1m0s
[12:39:19.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:25:0)
[12:39:19.149] Timer tick, asking Buildbucket for the build status
[12:39:19.929] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:19.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:26:0) after 1m0s
[12:40:19.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:26:0)
[12:40:19.988] Timer tick, asking Buildbucket for the build status
[12:40:20.467] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:27:0) after 1m0s
[12:41:20.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:27:0)
[12:41:20.500] Timer tick, asking Buildbucket for the build status
[12:41:20.676] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:20.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:28:0) after 1m0s
[12:42:20.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:28:0)
[12:42:20.708] Timer tick, asking Buildbucket for the build status
[12:42:20.844] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:20.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:29:0) after 1m0s
[12:43:20.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:29:0)
[12:43:20.868] Timer tick, asking Buildbucket for the build status
[12:43:21.010] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:21.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:30:0) after 1m0s
[12:44:21.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:30:0)
[12:44:21.085] Timer tick, asking Buildbucket for the build status
[12:44:21.210] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:21.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:31:0) after 1m0s
[12:45:21.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:31:0)
[12:45:21.273] Timer tick, asking Buildbucket for the build status
[12:45:21.492] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:21.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:32:0) after 1m0s
[12:46:21.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:32:0)
[12:46:21.518] Timer tick, asking Buildbucket for the build status
[12:46:22.322] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:22.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:33:0) after 1m0s
[12:47:22.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:33:0)
[12:47:22.385] Timer tick, asking Buildbucket for the build status
[12:47:22.524] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:22.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:34:0) after 1m0s
[12:48:22.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:34:0)
[12:48:22.565] Timer tick, asking Buildbucket for the build status
[12:48:22.921] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:22.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:35:0) after 1m0s
[12:49:22.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:35:0)
[12:49:22.998] Timer tick, asking Buildbucket for the build status
[12:49:23.129] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:23.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:36:0) after 1m0s
[12:50:23.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:36:0)
[12:50:23.256] Timer tick, asking Buildbucket for the build status
[12:50:23.383] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:23.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:37:0) after 1m0s
[12:51:23.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:37:0)
[12:51:23.431] Timer tick, asking Buildbucket for the build status
[12:51:23.609] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:23.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:38:0) after 1m0s
[12:52:23.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:38:0)
[12:52:23.652] Timer tick, asking Buildbucket for the build status
[12:52:23.886] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:23.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:39:0) after 1m0s
[12:53:23.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:39:0)
[12:53:23.944] Timer tick, asking Buildbucket for the build status
[12:53:24.103] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:24.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:40:0) after 1m0s
[12:54:24.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:40:0)
[12:54:24.118] Timer tick, asking Buildbucket for the build status
[12:54:24.242] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:24.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:41:0) after 1m0s
[12:55:24.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:41:0)
[12:55:24.291] Timer tick, asking Buildbucket for the build status
[12:55:24.370] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:24.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:42:0) after 1m0s
[12:56:24.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:42:0)
[12:56:24.435] Timer tick, asking Buildbucket for the build status
[12:56:24.895] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:24.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:43:0) after 1m0s
[12:57:24.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:43:0)
[12:57:24.961] Timer tick, asking Buildbucket for the build status
[12:57:25.041] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:25.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:44:0) after 1m0s
[12:58:25.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:44:0)
[12:58:25.076] Timer tick, asking Buildbucket for the build status
[12:58:25.158] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:25.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:45:0) after 1m0s
[12:59:25.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:45:0)
[12:59:25.195] Timer tick, asking Buildbucket for the build status
[12:59:25.311] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:25.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:46:0) after 1m0s
[13:00:25.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:46:0)
[13:00:25.648] Timer tick, asking Buildbucket for the build status
[13:00:25.769] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:25.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:47:0) after 1m0s
[13:01:26.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:47:0)
[13:01:26.257] Timer tick, asking Buildbucket for the build status
[13:01:26.368] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:26.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:48:0) after 1m0s
[13:02:26.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:48:0)
[13:02:26.708] Timer tick, asking Buildbucket for the build status
[13:02:26.789] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:26.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:49:0) after 1m0s
[13:03:26.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:49:0)
[13:03:26.852] Timer tick, asking Buildbucket for the build status
[13:03:27.314] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:50:0) after 1m0s
[13:04:27.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:50:0)
[13:04:27.394] Timer tick, asking Buildbucket for the build status
[13:04:27.821] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:27.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:51:0) after 1m0s
[13:05:27.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:51:0)
[13:05:27.867] Timer tick, asking Buildbucket for the build status
[13:05:28.110] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:28.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:52:0) after 1m0s
[13:06:28.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:52:0)
[13:06:28.173] Timer tick, asking Buildbucket for the build status
[13:06:28.919] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:28.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:53:0) after 1m0s
[13:07:28.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:53:0)
[13:07:28.951] Timer tick, asking Buildbucket for the build status
[13:07:30.159] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:30.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:54:0) after 1m0s
[13:08:30.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:54:0)
[13:08:30.206] Timer tick, asking Buildbucket for the build status
[13:08:30.613] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:30.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:55:0) after 1m0s
[13:09:30.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:55:0)
[13:09:30.647] Timer tick, asking Buildbucket for the build status
[13:09:30.801] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:30.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:56:0) after 1m0s
[13:10:30.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:56:0)
[13:10:30.838] Timer tick, asking Buildbucket for the build status
[13:10:30.926] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:30.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:57:0) after 1m0s
[13:11:30.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:57:0)
[13:11:30.967] Timer tick, asking Buildbucket for the build status
[13:11:31.441] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:31.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:58:0) after 1m0s
[13:12:31.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:58:0)
[13:12:31.446] Timer tick, asking Buildbucket for the build status
[13:12:31.536] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:31.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:59:0) after 1m0s
[13:13:31.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:59:0)
[13:13:31.587] Timer tick, asking Buildbucket for the build status
[13:13:31.689] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:31.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:60:0) after 1m0s
[13:14:31.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:60:0)
[13:14:31.754] Timer tick, asking Buildbucket for the build status
[13:14:31.848] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:31.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:61:0) after 1m0s
[13:15:31.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:61:0)
[13:15:31.886] Timer tick, asking Buildbucket for the build status
[13:15:31.968] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:31.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:62:0) after 1m0s
[13:16:31.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:62:0)
[13:16:31.988] Timer tick, asking Buildbucket for the build status
[13:16:32.107] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:32.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:63:0) after 1m0s
[13:17:32.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:63:0)
[13:17:32.134] Timer tick, asking Buildbucket for the build status
[13:17:32.279] Build 8862115615754407232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:32.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027545678031523504:64:0) after 1m0s
[13:18:03.306] Received PubSub notification, asking Buildbucket for the build status
[13:18:03.561] Build 8862115615754407232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:18:03.561] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1606828682369053",
"created_by": "project:chromium",
"created_ts": "1606824970440295",
"id": "8862115615754407232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5863b7e317f06f595f06ce54d33b0312ef88137\"}}",
"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\": \"d5863b7e317f06f595f06ce54d33b0312ef88137\", \"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-10-eupo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15967, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"df873e5fa6d33d5d402cf90dc6106e6812bc71f7\", \"got_dawn_revision\": \"a6a7e46e54518bd730caf47ef4d4a01ff81f9123\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d5863b7e317f06f595f06ce54d33b0312ef88137\", \"got_revision_cp\": \"refs/heads/master@{#832301}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"1eadcfb3be99c2eca944774a78c4e3c16132958b\", \"got_v8_revision\": \"f1e2bbd80f2e2eacf7e043e18ee55f0b8cf22171\", \"got_v8_revision_cp\": \"refs/heads/8.9.71@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"d5863b7e317f06f595f06ce54d33b0312ef88137\", \"swarm_hashes_refs/heads/master(at){#832301}_without_patch\": {\"absl_hardening_tests\": \"a46c58e7b26fe663bbb3e2a6410a5ef074e84ccf\", \"accessibility_unittests\": \"550362882a91ab993e73861a20408acf181d92cc\", \"angle_unittests\": \"c46c07ca5e5b370dadea75952959bd9f60e3680a\", \"app_shell_unittests\": \"02f069406b05cc04b20417ea5465b986c697b393\", \"aura_unittests\": \"55416981093b0f7ed15accbbdacd4792ca5486b6\", \"base_unittests\": \"0f8a69713e45a11a2a9bb4e5675d913136ef0ff3\", \"base_util_unittests\": \"a472347e2c8cc48a19df07ba192f583c3d34acb9\", \"blink_common_unittests\": \"aa783d28872e4c4eb878b0df1c5fe736e554c97e\", \"blink_fuzzer_unittests\": \"6c68f71ef6863e77bd0e481304b5f9415be14204\", \"blink_heap_unittests\": \"7a17d3f7160b8683ead3f2dab0017a429784b257\", \"blink_platform_unittests\": \"e5b4e5b8ce54321a90a46619e5de18798498d931\", \"blink_unittests\": \"d3174ef38e6b71b5b45368acc0982551187f2b33\", \"boringssl_crypto_tests\": \"10dc9f721d0a41d098a266dff9f1196da6bfbe40\", \"boringssl_ssl_tests\": \"e903ed21b1df04649ee90cde37d4d360e41b2de8\", \"browser_switcher_bho_unittests\": \"3bb341c7d6c92978a824d8d7bee0e4023d7aaf2b\", \"browser_tests\": \"2769894e84dae9a9528df90ea3cd7845b48afb10\", \"capture_unittests\": \"c7da393fcb7c76f5fe6bae2e75c96040fdf02dea\", \"cast_unittests\": \"7367e1754efd899d31a66d699557825f3cba7a92\", \"cc_unittests\": \"b95a775675c580d82ab0b5350370eac367c5a5a9\", \"chrome_app_unittests\": \"3bcd347d5993464064481d6614ba3f691de5771b\", \"chrome_cleaner_unittests\": \"31768a653d908bef6f2ffcf0b7ddb8bc41c24c52\", \"chrome_elf_unittests\": \"eff0c1e37f6dfc1d3e5b605a4568e2e135108934\", \"chromedriver_unittests\": \"26b82ecee8c3c3a2d76b1a5273e53c44a7eb72ac\", \"components_browsertests\": \"cbf00ee8392a7c5d8ba64c3a4224b2df4b2f1d2f\", \"components_unittests\": \"bc80ac2eac1ca54b30d2e39ad77f0cb50c52b104\", \"compositor_unittests\": \"9b1150104ce118a668971d9a139edeea71f720d1\", \"content_browsertests\": \"28ecf419a025fcbfde69f4328374a7502a731c11\", \"content_unittests\": \"6b75090b4a46e09d4efb8045ece2e9c6ef3589ed\", \"courgette_unittests\": \"1e170551d86feb2e0efad232e058852d9efe48f1\", \"crashpad_tests\": \"d41768400c9405dd3cfac90243c97a04eb7dc562\", \"cronet_tests\": \"cb7118a4c8e5bb734e25beff887ff45531928031\", \"cronet_unittests\": \"7145b99bdb9eeabf90b60a14e968f62fd5c84dbf\", \"crypto_unittests\": \"47b69d2f240f9c4368214f5f5fe0d3f8323832c3\", \"delayloads_unittests\": \"fa3ed44a954d1f1000f2ee23efcf558d481c2ddc\", \"device_unittests\": \"d1fb42634b5ce0cd609d9e5dfa6a012fbb126069\", \"display_unittests\": \"0622fa372eaef56b4143185d54976c6478142ca3\", \"elevation_service_unittests\": \"131ab27acfb7fd4f83c19e2c1bd309fadeb8ecab\", \"events_unittests\": \"5249ada5924af40413f0a258a0409c0111801e2c\", \"extensions_browsertests\": \"305495e5d6eb28f16c0f593e18d6531c9dc0cf6a\", \"extensions_unittests\": \"eec1f59869497ef00698ef4dcc435e623839c6f8\", \"filesystem_service_unittests\": \"7f91c09b01101582d1dadcbc7660577924b7d752\", \"gcm_unit_tests\": \"70ab1c30f2de68fa8a0528cb5d86ed0a719d6d20\", \"gcp_unittests\": \"82b0cefd281cfff82a23eb07a7ab974ae94a3a61\", \"gfx_unittests\": \"566d8f146dd5b0442f50d64f0d6d1cab7ea1f4c9\", \"gin_unittests\": \"6d370391af84fe31aefb1dc03731fba2ad203e80\", \"google_apis_unittests\": \"1fe06315f936d4c9009c68a000b68e9f639e01c0\", \"gpu_unittests\": \"f71960d31b3b10fa5d8cea067e75479400120b0f\", \"gwp_asan_unittests\": \"56d0b45c50fa0d46d9f677c5f25b5c0755b90dfd\", \"headless_browsertests\": \"84e42a20d06824f9042dd92d1461b0867c37aabb\", \"headless_unittests\": \"3921576fae6feda19707ce6c586885dced0965a4\", \"install_static_unittests\": \"d6a99b81f9b3405d5dcdf976fb486fe1d1343a6b\", \"installer_util_unittests\": \"dc370bb5bd13a0695bd5bc554f5fe54442c50e3f\", \"interactive_ui_tests\": \"3c7a9f27bf147b919f80b4b051f14cde0f431f8e\", \"ipc_tests\": \"514df5bcb673e8cd2d8f073f664d2a38a484f8e3\", \"jingle_unittests\": \"51d431d6bac8fcbd6cd6aad1d78ce5f351c234a8\", \"latency_unittests\": \"006d68c652c2b73169c8583e36769d37fe42581f\", \"libjingle_xmpp_unittests\": \"5a1b795a174b763f955002b4c0b365aca078abcd\", \"liburlpattern_unittests\": \"02391d14c3f1e446c4498413c74793b28a8adeeb\", \"media_blink_unittests\": \"9869509d1b791fa20d42ee70d0bdbe71e62d6303\", \"media_unittests\": \"ffc239a613f00b960bbf82d7324fdb8d338799c5\", \"message_center_unittests\": \"ae933f2e5e562a75f5acbdb5e67c413e472539ff\", \"midi_unittests\": \"38ba41d9d087297b41e333ee4107cdf89d69bca4\", \"mojo_core_unittests\": \"eb3dcc6aff1e4123b4a6440f9c953ee6b92ed028\", \"mojo_unittests\": \"bfe8eb23fdfb6bb9ac51bdcc995acf29e06fbd63\", \"nacl_loader_unittests\": \"8597c0a453c6956bbd0931d72b090b83a292d833\", \"native_theme_unittests\": \"4d947c5aaa6ca954ccdb151bf06bb665af699e40\", \"net_unittests\": \"7a2a6c92b15d97c9cae3f3cb8a42992422724e14\", \"notification_helper_unittests\": \"0de96e1ed500621b98154a9bc5aef3f2797c81c6\", \"pdf_unittests\": \"3e7feb24d4bfaac0fc086ba54d7f631b29682367\", \"perfetto_unittests\": \"0df840b2d7a5de929fca4475c830c19759d23cef\", \"ppapi_unittests\": \"781bc5a63c734a331be1d4cec56500ebe12d55ed\", \"printing_unittests\": \"1e1a02c49e2333ba5af79f4924f1e4372238f42b\", \"remoting_unittests\": \"345ff500b2e96327ad60e88dc48edd1e4fed5dd2\", \"sbox_integration_tests\": \"737cef9f4aad291bb979be73acb9e91580582482\", \"sbox_unittests\": \"63f8ae59cccd65f386503fed8af7793a37ab6dab\", \"sbox_validation_tests\": \"15b5eda17dc7070d2378cdea2c806da31e0e11c9\", \"service_manager_unittests\": \"e4c2ef73bafe48ad3dc99835f521ee46e50772ac\", \"services_unittests\": \"d9b6ae6e874900eb568acfd3210d4ef5d9e7431d\", \"setup_unittests\": \"a6990c84481480e7868111f68194cb665b5eb1eb\", \"shell_dialogs_unittests\": \"b135749fab6d1fd3b0f45d561a5b9ce0f9d21688\", \"skia_unittests\": \"eb7dfe6a30d0bf4ed282cbb395ad343f6618b10c\", \"snapshot_unittests\": \"971c559fd8913795036b75329ae9fb7f363ff7df\", \"sql_unittests\": \"c1fa1e5246c440472ffaf34f3ee04b60f1571672\", \"storage_unittests\": \"364025a7af42edf66eae55c7f5b33891402f0828\", \"sync_integration_tests\": \"b3e4c741190583570c1f632ed756e9c417ebead5\", \"traffic_annotation_auditor_unittests\": \"ac030209f039bb8bf336dbe4879e0ebd1132a681\", \"ui_base_unittests\": \"ba25e8e1a555b3aa7bca3f3d254eabb051bcf056\", \"ui_touch_selection_unittests\": \"b8a1887f68c61d20bd5e6dcc5725fc10d8845705\", \"unit_tests\": \"c0086187ef1d244412faa8830246cc72d2c9f3ac\", \"updater_tests\": \"3372d178de313a4544fd2afea48c407a202dbd49\", \"url_unittests\": \"9ef7a8a8a0f11ca49cfcace5f07578d9ee1b7eb0\", \"views_unittests\": \"f2c0deddb4bb5aac76e5a79462f1928a67ab4b30\", \"viz_unittests\": \"dde4d2c193d24fca67b8f946f66efc06ceaa1128\", \"vr_common_unittests\": \"53baf77547dd4d9339215ebcdbbaa475f73ac218\", \"vr_pixeltests\": \"9fd23374525edc4e72cf8e9d028bc5245f806f8f\", \"weblayer_browsertests\": \"e93b21ab72111853f834d03fea78f7cebc9ee3ed\", \"weblayer_unittests\": \"f7b0dad048e57a6279c5bbdea8c398c3727b12c7\", \"wm_unittests\": \"46205ce2d159db7eccaa3d9ee41e26c5d7520bd1\", \"wtf_unittests\": \"d5d915a0336711f557fa1bc3caee9cf6346d0ce2\", \"zlib_unittests\": \"b453f0ff79bfc1c1b58daedfca05ea854c768f97\", \"zucchini_unittests\": \"ff097ad93a53e093c7e6e6b1e71317a8309c3648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"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-10-eupo\"], \"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\": [\"5640-d0eb544\"], \"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": "1606824983847016",
"status": "COMPLETED",
"status_changed_ts": "1606828682759085",
"tags": [
"build_address:luci.chromium.ci/win-asan/15967",
"builder:win-asan",
"buildset:commit/git/d5863b7e317f06f595f06ce54d33b0312ef88137",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5863b7e317f06f595f06ce54d33b0312ef88137",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027545678031523504",
"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/8862115615754407232/+/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:50370ce886404010",
"user_agent:luci-scheduler"
],
"updated_ts": "1606828682766070",
"url": "https://ci.chromium.org/b/8862115615754407232",
"utcnow_ts": "1606828683546511"
}
}
[13:18:03.562] Invocation finished in 1h1m54.676996918s with status SUCCEEDED