[12:45:23.373] New invocation is queued and will start shortly
[12:45:24.567] Starting the invocation (attempt 1)
[12:45:24.611] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:45:24.611] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:45:24.611] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022832796088898752",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0afe22b82114da0b7768c14d963036d8b46a8023\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022832796088898752",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0afe22b82114da0b7768c14d963036d8b46a8023",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0afe22b82114da0b7768c14d963036d8b46a8023",
"gitiles_ref:refs/heads/master"
]
}
[12:45:25.216] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611319524728629",
"id": "8857402733996945664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0afe22b82114da0b7768c14d963036d8b46a8023\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611319525088990",
"tags": [
"build_address:luci.chromium.ci/win-asan/16948",
"builder:win-asan",
"buildset:commit/git/0afe22b82114da0b7768c14d963036d8b46a8023",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0afe22b82114da0b7768c14d963036d8b46a8023",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022832796088898752",
"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/8857402733996945664/+/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": "1611319525089293",
"url": "https://ci.chromium.org/b/8857402733996945664",
"utcnow_ts": "1611319525201246"
}
}
[12:45:25.216] Task URL: https://ci.chromium.org/b/8857402733996945664
[12:45:25.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:2:0) after 1m0s
[12:45:30.589] Received PubSub notification, asking Buildbucket for the build status
[12:45:30.652] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:2:0)
[12:46:25.330] Timer tick, asking Buildbucket for the build status
[12:46:25.419] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:4:0) after 1m0s
[12:47:25.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:4:0)
[12:47:25.558] Timer tick, asking Buildbucket for the build status
[12:47:26.226] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:26.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:5:0) after 1m0s
[12:48:26.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:5:0)
[12:48:26.447] Timer tick, asking Buildbucket for the build status
[12:48:26.576] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:26.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:6:0) after 1m0s
[12:49:26.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:6:0)
[12:49:26.781] Timer tick, asking Buildbucket for the build status
[12:49:26.901] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:26.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:7:0) after 1m0s
[12:50:26.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:7:0)
[12:50:26.990] Timer tick, asking Buildbucket for the build status
[12:50:27.133] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:8:0) after 1m0s
[12:51:27.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:8:0)
[12:51:27.210] Timer tick, asking Buildbucket for the build status
[12:51:27.281] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:9:0) after 1m0s
[12:52:27.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:9:0)
[12:52:27.339] Timer tick, asking Buildbucket for the build status
[12:52:27.415] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:27.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:10:0) after 1m0s
[12:53:27.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:10:0)
[12:53:27.527] Timer tick, asking Buildbucket for the build status
[12:53:27.912] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:27.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:11:0) after 1m0s
[12:54:27.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:11:0)
[12:54:27.932] Timer tick, asking Buildbucket for the build status
[12:54:27.995] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:27.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:12:0) after 1m0s
[12:55:28.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:12:0)
[12:55:28.024] Timer tick, asking Buildbucket for the build status
[12:55:28.413] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:28.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:13:0) after 1m0s
[12:56:28.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:13:0)
[12:56:28.423] Timer tick, asking Buildbucket for the build status
[12:56:28.479] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:28.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:14:0) after 1m0s
[12:57:28.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:14:0)
[12:57:28.504] Timer tick, asking Buildbucket for the build status
[12:57:28.793] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:28.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:15:0) after 1m0s
[12:58:28.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:15:0)
[12:58:28.866] Timer tick, asking Buildbucket for the build status
[12:58:29.008] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:29.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:16:0) after 1m0s
[12:59:29.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:16:0)
[12:59:29.031] Timer tick, asking Buildbucket for the build status
[12:59:29.106] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:29.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:17:0) after 1m0s
[13:00:29.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:17:0)
[13:00:29.164] Timer tick, asking Buildbucket for the build status
[13:00:29.335] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:29.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:18:0) after 1m0s
[13:01:29.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:18:0)
[13:01:29.917] Timer tick, asking Buildbucket for the build status
[13:01:30.000] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:30.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:19:0) after 1m0s
[13:02:30.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:19:0)
[13:02:30.176] Timer tick, asking Buildbucket for the build status
[13:02:30.328] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:20:0) after 1m0s
[13:03:30.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:20:0)
[13:03:30.524] Timer tick, asking Buildbucket for the build status
[13:03:30.588] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:21:0) after 1m0s
[13:04:30.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:21:0)
[13:04:30.801] Timer tick, asking Buildbucket for the build status
[13:04:31.112] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:31.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:22:0) after 1m0s
[13:05:31.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:22:0)
[13:05:31.259] Timer tick, asking Buildbucket for the build status
[13:05:31.369] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:31.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:23:0) after 1m0s
[13:06:31.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:23:0)
[13:06:31.616] Timer tick, asking Buildbucket for the build status
[13:06:32.225] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:32.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:24:0) after 1m0s
[13:07:32.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:24:0)
[13:07:32.342] Timer tick, asking Buildbucket for the build status
[13:07:32.455] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:32.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:25:0) after 1m0s
[13:08:32.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:25:0)
[13:08:32.533] Timer tick, asking Buildbucket for the build status
[13:08:33.958] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:33.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:26:0) after 1m0s
[13:09:33.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:26:0)
[13:09:33.983] Timer tick, asking Buildbucket for the build status
[13:09:34.055] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:34.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:27:0) after 1m0s
[13:10:34.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:27:0)
[13:10:34.142] Timer tick, asking Buildbucket for the build status
[13:10:34.282] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:34.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:28:0) after 1m0s
[13:11:34.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:28:0)
[13:11:34.322] Timer tick, asking Buildbucket for the build status
[13:11:34.460] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:34.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:29:0) after 1m0s
[13:12:34.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:29:0)
[13:12:34.508] Timer tick, asking Buildbucket for the build status
[13:12:34.936] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:34.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:30:0) after 1m0s
[13:13:34.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:30:0)
[13:13:34.975] Timer tick, asking Buildbucket for the build status
[13:13:35.130] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:35.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:31:0) after 1m0s
[13:14:35.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:31:0)
[13:14:35.157] Timer tick, asking Buildbucket for the build status
[13:14:35.445] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:35.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:32:0) after 1m0s
[13:15:35.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:32:0)
[13:15:35.428] Timer tick, asking Buildbucket for the build status
[13:15:35.749] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:35.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:33:0) after 1m0s
[13:16:35.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:33:0)
[13:16:35.774] Timer tick, asking Buildbucket for the build status
[13:16:35.901] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:35.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:34:0) after 1m0s
[13:17:35.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:34:0)
[13:17:35.931] Timer tick, asking Buildbucket for the build status
[13:17:36.302] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:36.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:35:0) after 1m0s
[13:18:36.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:35:0)
[13:18:36.326] Timer tick, asking Buildbucket for the build status
[13:18:36.449] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:36.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:36:0) after 1m0s
[13:19:36.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:36:0)
[13:19:36.498] Timer tick, asking Buildbucket for the build status
[13:19:36.633] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:36.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:37:0) after 1m0s
[13:20:36.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:37:0)
[13:20:36.696] Timer tick, asking Buildbucket for the build status
[13:20:36.900] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:36.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:38:0) after 1m0s
[13:21:36.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:38:0)
[13:21:36.972] Timer tick, asking Buildbucket for the build status
[13:21:37.094] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:39:0) after 1m0s
[13:22:37.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:39:0)
[13:22:37.206] Timer tick, asking Buildbucket for the build status
[13:22:37.485] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:37.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:40:0) after 1m0s
[13:23:37.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:40:0)
[13:23:37.545] Timer tick, asking Buildbucket for the build status
[13:23:38.073] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:38.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:41:0) after 1m0s
[13:24:38.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:41:0)
[13:24:38.149] Timer tick, asking Buildbucket for the build status
[13:24:38.533] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:38.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:42:0) after 1m0s
[13:25:38.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:42:0)
[13:25:38.557] Timer tick, asking Buildbucket for the build status
[13:25:38.698] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:38.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:43:0) after 1m0s
[13:26:38.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:43:0)
[13:26:38.755] Timer tick, asking Buildbucket for the build status
[13:26:38.843] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:38.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:44:0) after 1m0s
[13:27:38.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:44:0)
[13:27:38.901] Timer tick, asking Buildbucket for the build status
[13:27:38.997] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:38.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:45:0) after 1m0s
[13:28:39.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:45:0)
[13:28:39.062] Timer tick, asking Buildbucket for the build status
[13:28:39.205] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:39.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:46:0) after 1m0s
[13:29:39.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:46:0)
[13:29:39.357] Timer tick, asking Buildbucket for the build status
[13:29:39.487] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:39.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:47:0) after 1m0s
[13:30:39.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:47:0)
[13:30:39.563] Timer tick, asking Buildbucket for the build status
[13:30:40.005] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:40.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:48:0) after 1m0s
[13:31:40.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:48:0)
[13:31:40.051] Timer tick, asking Buildbucket for the build status
[13:31:40.411] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:40.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:49:0) after 1m0s
[13:32:40.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:49:0)
[13:32:40.493] Timer tick, asking Buildbucket for the build status
[13:32:41.344] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:41.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:50:0) after 1m0s
[13:33:41.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:50:0)
[13:33:41.403] Timer tick, asking Buildbucket for the build status
[13:33:41.939] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:41.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:51:0) after 1m0s
[13:34:41.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:51:0)
[13:34:41.960] Timer tick, asking Buildbucket for the build status
[13:34:42.519] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:42.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:52:0) after 1m0s
[13:35:42.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:52:0)
[13:35:42.630] Timer tick, asking Buildbucket for the build status
[13:35:42.777] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:42.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:53:0) after 1m0s
[13:36:42.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:53:0)
[13:36:42.902] Timer tick, asking Buildbucket for the build status
[13:36:43.415] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:43.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:54:0) after 1m0s
[13:37:43.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:54:0)
[13:37:43.489] Timer tick, asking Buildbucket for the build status
[13:37:43.617] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:55:0) after 1m0s
[13:38:43.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:55:0)
[13:38:43.665] Timer tick, asking Buildbucket for the build status
[13:38:43.880] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:43.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:56:0) after 1m0s
[13:39:43.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:56:0)
[13:39:43.942] Timer tick, asking Buildbucket for the build status
[13:39:44.647] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:44.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:57:0) after 1m0s
[13:40:44.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:57:0)
[13:40:44.671] Timer tick, asking Buildbucket for the build status
[13:40:45.552] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:45.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:58:0) after 1m0s
[13:41:45.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:58:0)
[13:41:45.595] Timer tick, asking Buildbucket for the build status
[13:41:45.727] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:45.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:59:0) after 1m0s
[13:42:45.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:59:0)
[13:42:45.768] Timer tick, asking Buildbucket for the build status
[13:42:46.179] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:46.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:60:0) after 1m0s
[13:43:46.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:60:0)
[13:43:46.250] Timer tick, asking Buildbucket for the build status
[13:43:46.453] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:46.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:61:0) after 1m0s
[13:44:46.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:61:0)
[13:44:46.446] Timer tick, asking Buildbucket for the build status
[13:44:46.804] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:62:0) after 1m0s
[13:45:46.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:62:0)
[13:45:46.789] Timer tick, asking Buildbucket for the build status
[13:45:47.369] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:47.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:63:0) after 1m0s
[13:46:47.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:63:0)
[13:46:47.497] Timer tick, asking Buildbucket for the build status
[13:46:47.931] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:64:0) after 1m0s
[13:47:48.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:64:0)
[13:47:48.029] Timer tick, asking Buildbucket for the build status
[13:47:48.102] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:48.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:65:0) after 1m0s
[13:48:48.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:65:0)
[13:48:48.085] Timer tick, asking Buildbucket for the build status
[13:48:48.216] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:48.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:66:0) after 1m0s
[13:49:48.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:66:0)
[13:49:48.519] Timer tick, asking Buildbucket for the build status
[13:49:49.025] Build 8857402733996945664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:49.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022832796088898752:67:0) after 1m0s
[13:50:26.517] Received PubSub notification, asking Buildbucket for the build status
[13:50:26.624] Build 8857402733996945664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:50:26.624] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611323425421974",
"created_by": "project:chromium",
"created_ts": "1611319524728629",
"id": "8857402733996945664",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0afe22b82114da0b7768c14d963036d8b46a8023\"}}",
"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\": \"0afe22b82114da0b7768c14d963036d8b46a8023\", \"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-2-2zr0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16948, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"c827ccbb58b8ceef91628527bad74fc90b135b61\", \"got_dawn_revision\": \"db383498c5c68ccc3c0eb10a72369c8dbe1c476a\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"0afe22b82114da0b7768c14d963036d8b46a8023\", \"got_revision_cp\": \"refs/heads/master@{#846076}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1d5174b9dc3be500d63daf227b163b5e86bbc7b5\", \"got_v8_revision\": \"49fd77f34678eb5d0c7f02fc0929701f5e180928\", \"got_v8_revision_cp\": \"refs/heads/9.0.49@{#1}\", \"got_webrtc_revision\": \"e4fd1ba3198932d4c4ca0e0cc98635758555adee\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33053}\", \"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\": \"0afe22b82114da0b7768c14d963036d8b46a8023\", \"swarm_hashes_refs/heads/master(at){#846076}_without_patch\": {\"absl_hardening_tests\": \"0ea17932fdae9b9bae0184148fa56f92bec03695\", \"accessibility_unittests\": \"c037ff89e7d9fc913dd6ae2aeea21f091e54c8f1\", \"angle_unittests\": \"e58b4fc2eb8fbc8300d6f0b44a3acee9fb29066d\", \"app_shell_unittests\": \"3abb1ca801e5433b4e353c159ad0e4e3ae450a14\", \"aura_unittests\": \"08614ba00e2b02976abea319b49ff9c8876f6755\", \"base_unittests\": \"8731e35c654fb870d75f3806a8685cde79322295\", \"base_util_unittests\": \"543b6cac0cc445f14ce58cdc6af4a5175b5cbb59\", \"blink_common_unittests\": \"93789723b76383fda3459c7aea706aa63dd191af\", \"blink_fuzzer_unittests\": \"c15d4ead700dc55bdda32b1a19137907dbb08025\", \"blink_heap_unittests\": \"2cb9d0b4bbd32b905f02c08a739bb7d134afcb32\", \"blink_platform_unittests\": \"bdadfbadd50b68195b96f11cc18dbd6e04da4b5c\", \"blink_unittests\": \"2277ee44c9aca5fd526dfe32aa94c881d3cfa17f\", \"boringssl_crypto_tests\": \"6512bcb2800a884f33f2b9cf409c5667419e8f04\", \"boringssl_ssl_tests\": \"6d1b7867ba9e75db1ddd2b920d17fbb844fd6dc3\", \"browser_switcher_bho_unittests\": \"6e12dbedcbfe0bd7f5bc445afa4e63700ee663bc\", \"browser_tests\": \"b575bb9a81fd66d8b57ccdb50018dce00037a6fb\", \"capture_unittests\": \"72c8341634a88e0ee62e4ca86f8d2f8f6a5afb4e\", \"cast_unittests\": \"935c487a39eacd5520a08e4736639b8a989278d4\", \"cc_unittests\": \"2b9abf9bec88b43e18060a06ddfa22d7b595a878\", \"chrome_app_unittests\": \"0ae803681390d2404b3365394a0ddb2e0ad009a6\", \"chrome_cleaner_unittests\": \"7615cd36216c2b0d6d9094eb87f9107b1aa979ad\", \"chrome_elf_unittests\": \"43490f46e4562576abab21c8fdd5563473330e32\", \"chromedriver_unittests\": \"f0ac4b4a1dd0f83d5e4bf4fe036a34ffba580c7b\", \"components_browsertests\": \"f5dc227abb9d7d6b77ddbf3aaf8ecc6387e9d059\", \"components_unittests\": \"9ba9cf6661120a87718fe30617f896b9137dfaeb\", \"compositor_unittests\": \"15fe6282d42a483cbab25c3e5766098b650cca8b\", \"content_browsertests\": \"8944220e64e02567ae29a3648585f4ea24ee8cf1\", \"content_unittests\": \"406d27e00322b68bb7c9471d16b14d95a884f924\", \"courgette_unittests\": \"c442e04f853de69634dc5ac88a4ef81732a70f44\", \"crashpad_tests\": \"b14a58ebd423f8cb6287f9ff9d0bb97745d28c0b\", \"cronet_tests\": \"d35eca74a8e33932976a8cb9e3c2a061b8ebddf1\", \"cronet_unittests\": \"e62e094fb416246f39f0bbbd81973c4a1b21b91a\", \"crypto_unittests\": \"6e6a8fd46b0d8596058b254a4c8422af850bb718\", \"delayloads_unittests\": \"b293ea9feeb501db018bc91e133964b3bf33fdcd\", \"device_unittests\": \"10bcfa0e851b75b3f3cf3d422b11e734924eda26\", \"display_unittests\": \"e8e5c23abccb3f3b3c6e6e415dc3919d7d50878c\", \"elevation_service_unittests\": \"79759d5b97fb48167472c23d3311c605cc35891d\", \"events_unittests\": \"9e360bd9df6e9968c6d52d0eb6f14680905f544d\", \"extensions_browsertests\": \"2aeac975a98a2130199caad3065b0cdfc92387d3\", \"extensions_unittests\": \"0afce7d8ea3642bb27990f2e6128abacba9107c4\", \"filesystem_service_unittests\": \"c4140a172362d35d415ea2a02d86b0c1e31145d3\", \"gcm_unit_tests\": \"12c37cb0a9f7cd37a544460e39424fae8e690b95\", \"gcp_unittests\": \"6780af12b2021243d6608314a751b22f0095789e\", \"gfx_unittests\": \"071cfb72fccf1c2edaa0b2858af3d90a7dbd52ad\", \"gin_unittests\": \"54276cb5ec451749764cbf39919225165aeb802b\", \"google_apis_unittests\": \"8c3ded5bbebf67ca6efd56e4eb60168369dc830e\", \"gpu_unittests\": \"c58d936969924d61f8ac7d2488b970242abfe6d2\", \"gwp_asan_unittests\": \"cc8aba61668bda5b074324d608cbb0a1c7cb9bbc\", \"headless_browsertests\": \"af75eef2570fce734d2ef2bc8363c5c98b5bfa11\", \"headless_unittests\": \"a857eeeb0fd1a4198466216d4683b79e7dff1fa9\", \"install_static_unittests\": \"f480b5a9220ca7b63aa7478befdb5ef19bc5e76e\", \"installer_util_unittests\": \"a532e284e4af9f0fe9814269cf3d319f9bb301bd\", \"interactive_ui_tests\": \"4664cef4950df66f54d803614b49451ca4dcab29\", \"ipc_tests\": \"bee575e3afa882710c7845514827c563bbd89d67\", \"jingle_unittests\": \"c2b363eeeb0360caaef130cb27115405093d2923\", \"latency_unittests\": \"31c2b9ba5762c3eeef50edb98b0b3ec8b9cd0537\", \"libjingle_xmpp_unittests\": \"791393a694a84fd3e14bc351ddeecc5ab251e501\", \"liburlpattern_unittests\": \"9013c6e7e82e133f1bacfc45af9552c37e7d6d88\", \"media_blink_unittests\": \"4106a9926090d6fa3f6e73edc94d95e67c9dae5f\", \"media_unittests\": \"fa3099653f0c1ffcfccdc56528e88169a3009261\", \"message_center_unittests\": \"e946333d454fdf51c1c2be29a45c706f2e1c6393\", \"midi_unittests\": \"12282322651dc76710941b48202fb7ec10c72110\", \"mojo_core_unittests\": \"54ed8f559d7681004b166cf74bebdc44062f50b1\", \"mojo_unittests\": \"4e730d910b07c854fa5e34603c4d14739b21decc\", \"nacl_loader_unittests\": \"a40a2fd20298453211a5f92af17bde3c69d5a75b\", \"native_theme_unittests\": \"8b29b9a2253bb795d6b15ebd9c085eed04805cd4\", \"net_unittests\": \"c30de36e09381b5b6e5c2794602e322e3a4abb4d\", \"notification_helper_unittests\": \"8e560beed5afca25715dc86f2455d91c5eb669c4\", \"pdf_unittests\": \"f92dd3aa7e25f7bf4539a2abded8060f5d4ea643\", \"perfetto_unittests\": \"fc4e43efcf86c43f6b91028ddd0cc841ccfadae9\", \"ppapi_unittests\": \"5bf57a00dc19b977a7c3dfdb236852ef7861afa6\", \"printing_unittests\": \"783f7c51cbedab1348a40c0dca7f5344abad0245\", \"remoting_unittests\": \"c4965f13983a5ea897708a9bd9e00f9ef7de74cf\", \"sbox_integration_tests\": \"a58179ab538676cbb4090568e2ae725a94ab724b\", \"sbox_unittests\": \"f069e92f31f7912e93ae195c920eec941770ef19\", \"sbox_validation_tests\": \"deda299dbe9ed089ccb6325d897bfcd821d6738f\", \"service_manager_unittests\": \"96f8d79ac12a4eccf90b705064ca720320fe8a36\", \"services_unittests\": \"2c7334a437e2d02a27597a68d5228ed438bcf0c3\", \"setup_unittests\": \"a32462a4571f1dc3a54ce70aca21c7c55fa7236d\", \"shell_dialogs_unittests\": \"eb7b3192cbdef84fb808dba470dbe1c630dfeda4\", \"skia_unittests\": \"b9903d86f930837475a3256b437a7002328ec4a9\", \"snapshot_unittests\": \"da4f225d860e2b97ff4cdb6ed0c76d576a547e97\", \"sql_unittests\": \"60e9882bc019f525c1762affd6a9d6e5d066dda2\", \"storage_unittests\": \"c103253fb02ecf0da9e6b902b373e2b25fc550d0\", \"sync_integration_tests\": \"b205feb79346bfd731bf90164d5563cb7c927d7d\", \"traffic_annotation_auditor_unittests\": \"758f1859655f5573df2493f0868a444c1c3fbec9\", \"ui_base_unittests\": \"6abd69976e5934df6528eb0b8cf62790b71c1a57\", \"ui_touch_selection_unittests\": \"cb5c9873c2d9f6be015eedb21171b028fd3671e3\", \"unit_tests\": \"632359ec97dfd45bf00a80153a69bcd72bb51ce4\", \"updater_tests\": \"03655d9d1fa0fc501e0577fe4f41c2096abd63cb\", \"url_unittests\": \"1293a5c559be988852ae72c6266e6c66f27255f6\", \"views_unittests\": \"eac08c0b6a34c7b9090881611dedd01b266b0bda\", \"viz_unittests\": \"f3d938c6deaa1d79e5b3c32a0076a2078d4eab5f\", \"vr_common_unittests\": \"9c3b4093faef29f0868c4092e3a2beb07252b080\", \"vr_pixeltests\": \"1dfc2697d78ff73e0fb9ba17211dc06fed619d37\", \"weblayer_browsertests\": \"5ffb13de30671206b4a6a06df3b8a7ec20f35879\", \"weblayer_unittests\": \"d69755dc7845e6a448d83c98b08feed8fec39a7c\", \"wm_unittests\": \"cb2a8ffe4b91ebdbac66c5f2c9205cd522a01e10\", \"wtf_unittests\": \"e5d571a1847ccec7a724d2e1e955f691a9e0a735\", \"zlib_unittests\": \"316b6e83b5b9d3d98cbd6782cca8530fdea93112\", \"zucchini_unittests\": \"e5cd77fc41a5de860dc1025eb24552ff6612e3a0\"}}, \"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-2-2zr0\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611319528696000",
"status": "COMPLETED",
"status_changed_ts": "1611323425822395",
"tags": [
"build_address:luci.chromium.ci/win-asan/16948",
"builder:win-asan",
"buildset:commit/git/0afe22b82114da0b7768c14d963036d8b46a8023",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0afe22b82114da0b7768c14d963036d8b46a8023",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022832796088898752",
"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/8857402733996945664/+/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:5142f24792c3c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611323425827275",
"url": "https://ci.chromium.org/b/8857402733996945664",
"utcnow_ts": "1611323426612638"
}
}
[13:50:26.624] Invocation finished in 1h5m3.273555272s with status SUCCEEDED