[22:33:30.909] New invocation is queued and will start shortly
[22:33:31.968] Starting the invocation (attempt 1)
[22:33:32.014] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:33:32.014] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:33:32.014] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021527436887487936",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c47b8ad3a78dedafeeeccb74f227ff4211978c91\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021527436887487936",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"gitiles_ref:refs/heads/master"
]
}
[22:33:34.303] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612564412132611",
"id": "8856097374942902352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c47b8ad3a78dedafeeeccb74f227ff4211978c91\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612564413724138",
"tags": [
"build_address:luci.chromium.ci/win-asan/17188",
"builder:win-asan",
"buildset:commit/git/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021527436887487936",
"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/8856097374942902352/+/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": "1612564413724864",
"url": "https://ci.chromium.org/b/8856097374942902352",
"utcnow_ts": "1612564414234511"
}
}
[22:33:34.303] Task URL: https://ci.chromium.org/b/8856097374942902352
[22:33:34.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:2:0) after 1m0s
[22:33:36.473] Received PubSub notification, asking Buildbucket for the build status
[22:33:36.573] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:34.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:2:0)
[22:34:34.822] Timer tick, asking Buildbucket for the build status
[22:34:34.908] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:34.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:4:0) after 1m0s
[22:35:35.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:4:0)
[22:35:35.091] Timer tick, asking Buildbucket for the build status
[22:35:35.190] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:35.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:5:0) after 1m0s
[22:36:35.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:5:0)
[22:36:35.285] Timer tick, asking Buildbucket for the build status
[22:36:35.911] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:35.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:6:0) after 1m0s
[22:37:36.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:6:0)
[22:37:36.012] Timer tick, asking Buildbucket for the build status
[22:37:36.131] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:36.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:7:0) after 1m0s
[22:38:36.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:7:0)
[22:38:36.277] Timer tick, asking Buildbucket for the build status
[22:38:36.384] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:36.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:8:0) after 1m0s
[22:39:36.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:8:0)
[22:39:36.416] Timer tick, asking Buildbucket for the build status
[22:39:36.567] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:36.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:9:0) after 1m0s
[22:40:36.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:9:0)
[22:40:36.663] Timer tick, asking Buildbucket for the build status
[22:40:36.825] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:10:0) after 1m0s
[22:41:36.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:10:0)
[22:41:36.898] Timer tick, asking Buildbucket for the build status
[22:41:36.953] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:36.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:11:0) after 1m0s
[22:42:37.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:11:0)
[22:42:37.055] Timer tick, asking Buildbucket for the build status
[22:42:37.121] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:37.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:12:0) after 1m0s
[22:43:37.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:12:0)
[22:43:37.208] Timer tick, asking Buildbucket for the build status
[22:43:37.306] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:37.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:13:0) after 1m0s
[22:44:37.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:13:0)
[22:44:37.399] Timer tick, asking Buildbucket for the build status
[22:44:37.505] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:37.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:14:0) after 1m0s
[22:45:37.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:14:0)
[22:45:37.584] Timer tick, asking Buildbucket for the build status
[22:45:37.681] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:37.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:15:0) after 1m0s
[22:46:37.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:15:0)
[22:46:37.796] Timer tick, asking Buildbucket for the build status
[22:46:37.858] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:16:0) after 1m0s
[22:47:37.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:16:0)
[22:47:37.908] Timer tick, asking Buildbucket for the build status
[22:47:37.966] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:17:0) after 1m0s
[22:48:38.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:17:0)
[22:48:38.076] Timer tick, asking Buildbucket for the build status
[22:48:38.201] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:18:0) after 1m0s
[22:49:38.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:18:0)
[22:49:38.374] Timer tick, asking Buildbucket for the build status
[22:49:38.444] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:19:0) after 1m0s
[22:50:38.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:19:0)
[22:50:38.589] Timer tick, asking Buildbucket for the build status
[22:50:38.811] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:20:0) after 1m0s
[22:51:39.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:20:0)
[22:51:39.028] Timer tick, asking Buildbucket for the build status
[22:51:39.098] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:39.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:21:0) after 1m0s
[22:52:39.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:21:0)
[22:52:39.309] Timer tick, asking Buildbucket for the build status
[22:52:39.530] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:39.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:22:0) after 1m0s
[22:53:39.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:22:0)
[22:53:39.595] Timer tick, asking Buildbucket for the build status
[22:53:39.947] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:23:0) after 1m0s
[22:54:39.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:23:0)
[22:54:39.957] Timer tick, asking Buildbucket for the build status
[22:54:40.109] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:40.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:24:0) after 1m0s
[22:55:40.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:24:0)
[22:55:40.172] Timer tick, asking Buildbucket for the build status
[22:55:40.903] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:40.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:25:0) after 1m0s
[22:56:41.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:25:0)
[22:56:41.006] Timer tick, asking Buildbucket for the build status
[22:56:41.297] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:41.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:26:0) after 1m0s
[22:57:41.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:26:0)
[22:57:41.338] Timer tick, asking Buildbucket for the build status
[22:57:41.696] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:41.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:27:0) after 1m0s
[22:58:41.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:27:0)
[22:58:41.700] Timer tick, asking Buildbucket for the build status
[22:58:42.462] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:42.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:28:0) after 1m0s
[22:59:42.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:28:0)
[22:59:42.448] Timer tick, asking Buildbucket for the build status
[22:59:42.704] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:42.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:29:0) after 1m0s
[23:00:42.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:29:0)
[23:00:42.738] Timer tick, asking Buildbucket for the build status
[23:00:43.038] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:43.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:30:0) after 1m0s
[23:01:43.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:30:0)
[23:01:43.088] Timer tick, asking Buildbucket for the build status
[23:01:43.311] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:43.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:31:0) after 1m0s
[23:02:43.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:31:0)
[23:02:43.390] Timer tick, asking Buildbucket for the build status
[23:02:43.814] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:43.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:32:0) after 1m0s
[23:03:43.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:32:0)
[23:03:43.910] Timer tick, asking Buildbucket for the build status
[23:03:44.345] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:44.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:33:0) after 1m0s
[23:04:44.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:33:0)
[23:04:44.378] Timer tick, asking Buildbucket for the build status
[23:04:45.109] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:34:0) after 1m0s
[23:05:45.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:34:0)
[23:05:45.131] Timer tick, asking Buildbucket for the build status
[23:05:45.263] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:45.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:35:0) after 1m0s
[23:06:45.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:35:0)
[23:06:45.285] Timer tick, asking Buildbucket for the build status
[23:06:45.451] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:36:0) after 1m0s
[23:07:45.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:36:0)
[23:07:45.514] Timer tick, asking Buildbucket for the build status
[23:07:45.634] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:45.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:37:0) after 1m0s
[23:08:45.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:37:0)
[23:08:45.767] Timer tick, asking Buildbucket for the build status
[23:08:46.045] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:46.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:38:0) after 1m0s
[23:09:46.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:38:0)
[23:09:46.194] Timer tick, asking Buildbucket for the build status
[23:09:46.272] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:46.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:39:0) after 1m0s
[23:10:46.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:39:0)
[23:10:46.547] Timer tick, asking Buildbucket for the build status
[23:10:46.615] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:46.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:40:0) after 1m0s
[23:11:46.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:40:0)
[23:11:46.789] Timer tick, asking Buildbucket for the build status
[23:11:46.887] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:46.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:41:0) after 1m0s
[23:12:47.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:41:0)
[23:12:47.121] Timer tick, asking Buildbucket for the build status
[23:12:47.182] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:47.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:42:0) after 1m0s
[23:13:47.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:42:0)
[23:13:47.298] Timer tick, asking Buildbucket for the build status
[23:13:47.405] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:47.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:43:0) after 1m0s
[23:14:47.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:43:0)
[23:14:47.498] Timer tick, asking Buildbucket for the build status
[23:14:47.572] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:47.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:44:0) after 1m0s
[23:15:47.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:44:0)
[23:15:47.715] Timer tick, asking Buildbucket for the build status
[23:15:47.828] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:47.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:45:0) after 1m0s
[23:16:47.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:45:0)
[23:16:47.881] Timer tick, asking Buildbucket for the build status
[23:16:48.273] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:48.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:46:0) after 1m0s
[23:17:48.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:46:0)
[23:17:48.310] Timer tick, asking Buildbucket for the build status
[23:17:48.423] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:48.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:47:0) after 1m0s
[23:18:48.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:47:0)
[23:18:48.504] Timer tick, asking Buildbucket for the build status
[23:18:48.618] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:48.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:48:0) after 1m0s
[23:19:48.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:48:0)
[23:19:48.768] Timer tick, asking Buildbucket for the build status
[23:19:48.843] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:48.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:49:0) after 1m0s
[23:20:48.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:49:0)
[23:20:48.961] Timer tick, asking Buildbucket for the build status
[23:20:51.311] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:51.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:50:0) after 1m0s
[23:21:51.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:50:0)
[23:21:51.384] Timer tick, asking Buildbucket for the build status
[23:21:51.515] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:51.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:51:0) after 1m0s
[23:22:51.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:51:0)
[23:22:51.554] Timer tick, asking Buildbucket for the build status
[23:22:51.847] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:51.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:52:0) after 1m0s
[23:23:51.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:52:0)
[23:23:51.913] Timer tick, asking Buildbucket for the build status
[23:23:52.026] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:52.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:53:0) after 1m0s
[23:24:52.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:53:0)
[23:24:52.071] Timer tick, asking Buildbucket for the build status
[23:24:52.317] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:52.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:54:0) after 1m0s
[23:25:52.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:54:0)
[23:25:52.364] Timer tick, asking Buildbucket for the build status
[23:25:52.788] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:52.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:55:0) after 1m0s
[23:26:52.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:55:0)
[23:26:52.838] Timer tick, asking Buildbucket for the build status
[23:26:52.991] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:52.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:56:0) after 1m0s
[23:27:53.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:56:0)
[23:27:53.013] Timer tick, asking Buildbucket for the build status
[23:27:53.201] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:53.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:57:0) after 1m0s
[23:28:53.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:57:0)
[23:28:53.323] Timer tick, asking Buildbucket for the build status
[23:28:53.503] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:53.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:58:0) after 1m0s
[23:29:53.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:58:0)
[23:29:53.523] Timer tick, asking Buildbucket for the build status
[23:29:53.594] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:53.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:59:0) after 1m0s
[23:30:53.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:59:0)
[23:30:53.619] Timer tick, asking Buildbucket for the build status
[23:30:53.697] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:60:0) after 1m0s
[23:31:53.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:60:0)
[23:31:53.762] Timer tick, asking Buildbucket for the build status
[23:31:53.832] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:61:0) after 1m0s
[23:32:53.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:61:0)
[23:32:53.925] Timer tick, asking Buildbucket for the build status
[23:32:53.998] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:53.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:62:0) after 1m0s
[23:33:54.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:62:0)
[23:33:54.046] Timer tick, asking Buildbucket for the build status
[23:33:54.173] Build 8856097374942902352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021527436887487936:63:0) after 1m0s
[23:34:43.863] Received PubSub notification, asking Buildbucket for the build status
[23:34:44.047] Build 8856097374942902352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:34:44.047] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612568083164655",
"created_by": "project:chromium",
"created_ts": "1612564412132611",
"id": "8856097374942902352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c47b8ad3a78dedafeeeccb74f227ff4211978c91\"}}",
"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\": \"c47b8ad3a78dedafeeeccb74f227ff4211978c91\", \"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-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17188, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"3bb5f1ff26bccc4f409cedd196e9749f76bd6869\", \"got_dawn_revision\": \"14a2398e711f5df5dbb0ec45999d6b16c1e80304\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"c47b8ad3a78dedafeeeccb74f227ff4211978c91\", \"got_revision_cp\": \"refs/heads/master@{#851310}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"c47b8ad3a78dedafeeeccb74f227ff4211978c91\", \"swarm_hashes_refs/heads/master(at){#851310}_without_patch\": {\"absl_hardening_tests\": \"b2896a3fdbe8c54d7ff1c9154b2e7c0ca455f509\", \"accessibility_unittests\": \"33f3bf721e590a2dc8b8abb33441df095f2ac15b\", \"angle_unittests\": \"be859329f8da870da463d1260594a72402b3f4d7\", \"app_shell_unittests\": \"9127acdb2d81d37b295b38dc12064c792d0841b3\", \"aura_unittests\": \"c53ddc2ade7e013799c3542f9cc761c91abe0f93\", \"base_unittests\": \"ce29278ee295220081824a5f814e53c0ce6a32f8\", \"base_util_unittests\": \"2c248a2bc780d6064523f2a340c47ea37e78a0bf\", \"blink_common_unittests\": \"1f7bfaf55c27f8ad00381ca4f0e6d66f66e0d84e\", \"blink_fuzzer_unittests\": \"314128a8a1f5a974e923d500d1965b012ec3fe02\", \"blink_heap_unittests\": \"ad84de052a0cfb92d2c0bf2acbabd7d45459fee0\", \"blink_platform_unittests\": \"be6e1c035ebca8439eb096bd2a91e78fe60757a3\", \"blink_unittests\": \"cbbfa50833e14be99c7fc67f100f2cb9785bfe8a\", \"boringssl_crypto_tests\": \"3674119f78b6fb5d448b1aa047cb291de218f4c5\", \"boringssl_ssl_tests\": \"b656410778ce2143a121e41c0836d51479464557\", \"browser_switcher_bho_unittests\": \"485b19d0933fe8722f2fcb7cccf257b89b290d3d\", \"browser_tests\": \"a13062e6348d3492760f66c70530c049725b241d\", \"capture_unittests\": \"2b617c2fb806a68aa7447f081ccdd6d1d49b4663\", \"cast_unittests\": \"4eb4e836fc52c23e7af0b3385725db2b82359590\", \"cc_unittests\": \"4a809279d0fd8b639684067758d773d678f72059\", \"chrome_app_unittests\": \"71bb8aa0bd1b5ddbaea2177e8bbb83127bc29fe2\", \"chrome_cleaner_unittests\": \"176e5f8c8f23736f7a9e5ec25f6008f278ded6e0\", \"chrome_elf_unittests\": \"46576aab0ed97dae44d0721d064a025f59494dd1\", \"chromedriver_unittests\": \"4fa572ff69b6c05472f811e3718777d3acc35927\", \"components_browsertests\": \"ce6c1f11cc5d64119d72727003f49bd1999b0214\", \"components_unittests\": \"30f48f83eb6a58446059342116fb2f38bbcb8f28\", \"compositor_unittests\": \"4ce7869a4bca6880e4ee8c6989ba663c96c60845\", \"content_browsertests\": \"73ce0e8ccd5c78271754b1da4a359165ac47e014\", \"content_unittests\": \"db0399cccc90d317b8962f57e8551bbe72df693a\", \"courgette_unittests\": \"5bb28a874982b2b0aa9eab9fd0768157b9d31738\", \"crashpad_tests\": \"1d8dba17352ed252fecb495c2988d8cdbd3da1d1\", \"cronet_tests\": \"0a8f3c630a35ce78312777fe37893ada1fffb1fd\", \"cronet_unittests\": \"2968a6b8b2f685f3e00074197e3c149a1defedb7\", \"crypto_unittests\": \"a44bfa3aa10c02b82d36f41ee5ad7e5c06002437\", \"delayloads_unittests\": \"7bda059f17407b1e9e2825f0f5ba05eab47eb032\", \"device_unittests\": \"8971ea65543ada2f90e1ec96a4235a101b48b31b\", \"display_unittests\": \"8d78109dffebe2999c630d6b4b0a7614e95211ad\", \"elevation_service_unittests\": \"07f56ee97245ba61b9f7f442822c86af7727a1b0\", \"events_unittests\": \"bd0854712a969acf8b078f856437718566a557c2\", \"extensions_browsertests\": \"add8e9aae77da35f17a8285a250f194ee0e89e8a\", \"extensions_unittests\": \"d6066964b889215ecb87e565ad6f4f1bcff1fbd6\", \"filesystem_service_unittests\": \"8c0095d12263818d84f2f29f37a3a0329688ebf3\", \"gcm_unit_tests\": \"4cc5cefffbdac8a0473112730804d0037e49a19d\", \"gcp_unittests\": \"a86445f219246e12b24a38d786493d7d04e59e58\", \"gfx_unittests\": \"621672011bf587e0c6863a3f0bbfc6e494363b9f\", \"gin_unittests\": \"3ec35c4a461171ce6312c9a0e6313134784e86d7\", \"google_apis_unittests\": \"1d6c9231c7aaacfc29ad877957cb1bf885434b73\", \"gpu_unittests\": \"0665095c7c5793623b6d8eba0de24f305e497143\", \"gwp_asan_unittests\": \"3f12a26ac05fc188722b1db09323f2fb66f09567\", \"headless_browsertests\": \"04614fe79fd34c25ce736dbb81f163554832db2b\", \"headless_unittests\": \"b2f1917b9577e93b9fef270bbfc2984f98edffa6\", \"install_static_unittests\": \"050752ffaa49ca6c7bb31e63880f6e43afdfbcfc\", \"installer_util_unittests\": \"60a3fc08ea09cf1068755523e2e9db9bf07d60cf\", \"interactive_ui_tests\": \"110e6ae594d28807ff9599fc76ec62a708209be3\", \"ipc_tests\": \"7e9e47ef7f9a6388b3eb54368beec43fab7966ea\", \"jingle_unittests\": \"c21fd6cc1482fcd80fe184a888726aef46fd610b\", \"latency_unittests\": \"00f1ec7748de4336a22add07c39739290da4ab40\", \"libjingle_xmpp_unittests\": \"042e64b24d1e5943bbe463c185c31fac75b363bc\", \"liburlpattern_unittests\": \"076a596ba380ece726dc4ad95ee284b217d46171\", \"media_blink_unittests\": \"a631f006f3fc880926cb9db0e4f5422a42454b01\", \"media_unittests\": \"6a10c904595d1437a6cbb4b0f739eab4dd5a9d5d\", \"message_center_unittests\": \"cf1e58f48b53a02ef6b5977e1d488b3ef5b8ddb8\", \"midi_unittests\": \"40984b70f42f3687f6ba25c7cc3147d2253844f2\", \"mojo_core_unittests\": \"cd9080ce16bce4c08794a90dde3c7385a1967165\", \"mojo_unittests\": \"96ff9fb13c3361a62f8596cd904cee11079ffb8e\", \"nacl_loader_unittests\": \"7e5cea25d5cc3511fc97de4b086c560f61c8107c\", \"native_theme_unittests\": \"e604b38b97f81f60ef77b400252ca1949a0cabed\", \"net_unittests\": \"db3f5aa313755181e0dd7beaad6b1d2befebc161\", \"notification_helper_unittests\": \"bef0635c76ce7828b77bfac11d5dcbed044d5844\", \"pdf_unittests\": \"b7a7d3c28703a0cf494d0076ed3e4c7c128bd3fb\", \"perfetto_unittests\": \"bbe75d2bdc74c9576bfa4ab397fb1a8110f6ffcc\", \"ppapi_unittests\": \"0fdcaf5b80e5ae40a7b76d6f4d3dc8320fbedbcd\", \"printing_unittests\": \"a728037100c3ab9528058c1297bd96291c2df17a\", \"remoting_unittests\": \"c2cdce35e32f8f1953cb5e676fb9bc48f91940d5\", \"sbox_integration_tests\": \"ceb145e70e83db115a68616488ea69746d2a4ed3\", \"sbox_unittests\": \"cc7e6802547b966100315e37d85b0498676fe507\", \"sbox_validation_tests\": \"fe65068fd49c626fb8ba5c82baee640092152582\", \"service_manager_unittests\": \"0d648a8ca8a1105d0a7f3e6d464b0dee1d726a93\", \"services_unittests\": \"507a30a5d3743ee587ffdec699b2bc4e4d57b075\", \"setup_unittests\": \"6d37c83ba7f231808cb4f8dd5b2c32f70e26093b\", \"shell_dialogs_unittests\": \"2981d0961585ecbd15add61f6005c9484d3372f9\", \"skia_unittests\": \"c4bc63015a82e800e2dc85d391fb268ba614ba7b\", \"snapshot_unittests\": \"c49ac7856d121fe2b7957da0b1415445effd75b1\", \"sql_unittests\": \"287ecaa16029c7f8d648c4c198a5b84679bde1d6\", \"storage_unittests\": \"f8b338668fd191372066b8502dc5ec7998bf0cfd\", \"sync_integration_tests\": \"42bccf4792214f1464d17951291bf336048dad2f\", \"traffic_annotation_auditor_unittests\": \"77c23312e8387450d86d7f15dcf9d40de9adfebd\", \"ui_base_unittests\": \"93e581382d39052cb8e8f361bdd88c6ccae30579\", \"ui_touch_selection_unittests\": \"2e34db2296c52e1d0bb57ce99bf58b3e1f262a20\", \"unit_tests\": \"4546d0b60db144067bfff4367bf637b30f0ebb6c\", \"updater_tests\": \"ec260ea231d1824a5fcb9c4ca259bfa514670550\", \"url_unittests\": \"c8f4123b5480720d5bc8eea9ce9fd4f67211c828\", \"views_unittests\": \"eb7568e3e9b1d0f38b6ccee84a4341f0eeb7bb97\", \"viz_unittests\": \"05c0560115359e86fe0ff70cb745966b6f6775a9\", \"vr_common_unittests\": \"38e9bbbc7e021010f3fae0e35dd58cb5ceaa0a59\", \"vr_pixeltests\": \"950a689ea17558341d2d0c6d2f4bb0c18478805c\", \"weblayer_browsertests\": \"e275ae192ea5f222b7ddcbf8704c08cca8b8a1db\", \"weblayer_unittests\": \"8f744e3789a78ca35621e04871c10d3e5805df27\", \"wm_unittests\": \"f488b7df6ba46bfb71f46668a7a958b3d4b6ea8d\", \"wtf_unittests\": \"9ad8c0e6be2b400daa3a4c36b93017986eb43843\", \"zlib_unittests\": \"f9bd2c16fb23e24354aaa87b1c4b00636cfdefec\", \"zucchini_unittests\": \"f5b848abd7dc7977e44a5489ff673f44f2999a96\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612564415562420",
"status": "COMPLETED",
"status_changed_ts": "1612568083441704",
"tags": [
"build_address:luci.chromium.ci/win-asan/17188",
"builder:win-asan",
"buildset:commit/git/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c47b8ad3a78dedafeeeccb74f227ff4211978c91",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021527436887487936",
"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/8856097374942902352/+/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:518d25c9a1b36410",
"user_agent:luci-scheduler"
],
"updated_ts": "1612568083446116",
"url": "https://ci.chromium.org/b/8856097374942902352",
"utcnow_ts": "1612568084030504"
}
}
[23:34:44.047] Invocation finished in 1h1m13.152183993s with status SUCCEEDED