[15:08:21.203] New invocation is queued and will start shortly
[15:08:22.520] Starting the invocation (attempt 1)
[15:08:22.589] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:08:22.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:08:22.589] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029165589224757328",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9610fc68fc6a584bfabb132a32860e0ef5443fd7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029165589224757328",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"gitiles_ref:refs/heads/master"
]
}
[15:08:23.686] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1605280102760235",
"id": "8863735526926380800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9610fc68fc6a584bfabb132a32860e0ef5443fd7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605280103406062",
"tags": [
"build_address:luci.chromium.ci/win-asan/15621",
"builder:win-asan",
"buildset:commit/git/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029165589224757328",
"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/8863735526926380800/+/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": "1605280103406244",
"url": "https://ci.chromium.org/b/8863735526926380800",
"utcnow_ts": "1605280103673550"
}
}
[15:08:23.686] Task URL: https://ci.chromium.org/b/8863735526926380800
[15:08:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:2:0) after 1m0s
[15:08:25.938] Received PubSub notification, asking Buildbucket for the build status
[15:08:30.846] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:23.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:2:0)
[15:09:23.730] Timer tick, asking Buildbucket for the build status
[15:09:23.940] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:4:0) after 1m0s
[15:10:23.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:4:0)
[15:10:23.988] Timer tick, asking Buildbucket for the build status
[15:10:24.121] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:24.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:5:0) after 1m0s
[15:11:24.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:5:0)
[15:11:24.215] Timer tick, asking Buildbucket for the build status
[15:11:24.331] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:24.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:6:0) after 1m0s
[15:12:24.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:6:0)
[15:12:24.374] Timer tick, asking Buildbucket for the build status
[15:12:24.533] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:24.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:7:0) after 1m0s
[15:13:24.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:7:0)
[15:13:24.527] Timer tick, asking Buildbucket for the build status
[15:13:24.746] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:24.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:8:0) after 1m0s
[15:14:24.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:8:0)
[15:14:24.830] Timer tick, asking Buildbucket for the build status
[15:14:25.031] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:25.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:9:0) after 1m0s
[15:15:25.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:9:0)
[15:15:25.075] Timer tick, asking Buildbucket for the build status
[15:15:27.341] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:27.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:10:0) after 1m0s
[15:16:27.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:10:0)
[15:16:27.425] Timer tick, asking Buildbucket for the build status
[15:16:27.492] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:27.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:11:0) after 1m0s
[15:17:27.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:11:0)
[15:17:27.542] Timer tick, asking Buildbucket for the build status
[15:17:27.641] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:27.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:12:0) after 1m0s
[15:18:27.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:12:0)
[15:18:27.808] Timer tick, asking Buildbucket for the build status
[15:18:27.930] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:27.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:13:0) after 1m0s
[15:19:28.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:13:0)
[15:19:28.051] Timer tick, asking Buildbucket for the build status
[15:19:28.132] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:28.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:14:0) after 1m0s
[15:20:28.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:14:0)
[15:20:28.224] Timer tick, asking Buildbucket for the build status
[15:20:28.306] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:15:0) after 1m0s
[15:21:28.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:15:0)
[15:21:28.558] Timer tick, asking Buildbucket for the build status
[15:21:31.679] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:31.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:16:0) after 1m0s
[15:22:31.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:16:0)
[15:22:31.824] Timer tick, asking Buildbucket for the build status
[15:22:31.877] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:31.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:17:0) after 1m0s
[15:23:32.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:17:0)
[15:23:32.006] Timer tick, asking Buildbucket for the build status
[15:23:32.143] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:18:0) after 1m0s
[15:24:32.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:18:0)
[15:24:32.181] Timer tick, asking Buildbucket for the build status
[15:24:32.285] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:32.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:19:0) after 1m0s
[15:25:32.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:19:0)
[15:25:32.360] Timer tick, asking Buildbucket for the build status
[15:25:32.430] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:32.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:20:0) after 1m0s
[15:26:32.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:20:0)
[15:26:32.541] Timer tick, asking Buildbucket for the build status
[15:26:32.649] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:32.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:21:0) after 1m0s
[15:27:32.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:21:0)
[15:27:32.681] Timer tick, asking Buildbucket for the build status
[15:27:32.839] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:32.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:22:0) after 1m0s
[15:28:32.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:22:0)
[15:28:32.861] Timer tick, asking Buildbucket for the build status
[15:28:32.984] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:32.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:23:0) after 1m0s
[15:29:33.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:23:0)
[15:29:33.057] Timer tick, asking Buildbucket for the build status
[15:29:33.167] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:33.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:24:0) after 1m0s
[15:30:33.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:24:0)
[15:30:33.259] Timer tick, asking Buildbucket for the build status
[15:30:33.460] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:25:0) after 1m0s
[15:31:33.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:25:0)
[15:31:33.568] Timer tick, asking Buildbucket for the build status
[15:31:33.705] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:33.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:26:0) after 1m0s
[15:32:33.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:26:0)
[15:32:33.788] Timer tick, asking Buildbucket for the build status
[15:32:34.184] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:34.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:27:0) after 1m0s
[15:33:34.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:27:0)
[15:33:34.209] Timer tick, asking Buildbucket for the build status
[15:33:34.651] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:34.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:28:0) after 1m0s
[15:34:34.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:28:0)
[15:34:34.692] Timer tick, asking Buildbucket for the build status
[15:34:35.102] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:35.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:29:0) after 1m0s
[15:35:35.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:29:0)
[15:35:35.095] Timer tick, asking Buildbucket for the build status
[15:35:35.213] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:35.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:30:0) after 1m0s
[15:36:35.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:30:0)
[15:36:35.211] Timer tick, asking Buildbucket for the build status
[15:36:35.342] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:35.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:31:0) after 1m0s
[15:37:35.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:31:0)
[15:37:35.425] Timer tick, asking Buildbucket for the build status
[15:37:35.562] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:35.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:32:0) after 1m0s
[15:38:35.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:32:0)
[15:38:35.616] Timer tick, asking Buildbucket for the build status
[15:38:35.820] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:35.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:33:0) after 1m0s
[15:39:35.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:33:0)
[15:39:35.926] Timer tick, asking Buildbucket for the build status
[15:39:38.692] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:38.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:34:0) after 1m0s
[15:40:38.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:34:0)
[15:40:38.708] Timer tick, asking Buildbucket for the build status
[15:40:38.847] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:38.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:35:0) after 1m0s
[15:41:38.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:35:0)
[15:41:38.916] Timer tick, asking Buildbucket for the build status
[15:41:39.039] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:39.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:36:0) after 1m0s
[15:42:39.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:36:0)
[15:42:39.127] Timer tick, asking Buildbucket for the build status
[15:42:39.702] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:39.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:37:0) after 1m0s
[15:43:39.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:37:0)
[15:43:39.719] Timer tick, asking Buildbucket for the build status
[15:43:40.222] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:40.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:38:0) after 1m0s
[15:44:40.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:38:0)
[15:44:40.243] Timer tick, asking Buildbucket for the build status
[15:44:42.798] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:42.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:39:0) after 1m0s
[15:45:42.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:39:0)
[15:45:42.881] Timer tick, asking Buildbucket for the build status
[15:45:43.134] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:43.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:40:0) after 1m0s
[15:46:43.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:40:0)
[15:46:43.176] Timer tick, asking Buildbucket for the build status
[15:46:43.361] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:43.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:41:0) after 1m0s
[15:47:43.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:41:0)
[15:47:43.395] Timer tick, asking Buildbucket for the build status
[15:47:44.029] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:44.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:42:0) after 1m0s
[15:48:44.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:42:0)
[15:48:44.091] Timer tick, asking Buildbucket for the build status
[15:48:46.200] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:46.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:43:0) after 1m0s
[15:49:46.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:43:0)
[15:49:46.229] Timer tick, asking Buildbucket for the build status
[15:49:46.508] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:46.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:44:0) after 1m0s
[15:50:46.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:44:0)
[15:50:46.550] Timer tick, asking Buildbucket for the build status
[15:50:46.646] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:46.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:45:0) after 1m0s
[15:51:46.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:45:0)
[15:51:46.723] Timer tick, asking Buildbucket for the build status
[15:51:47.106] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:47.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:46:0) after 1m0s
[15:52:47.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:46:0)
[15:52:47.195] Timer tick, asking Buildbucket for the build status
[15:52:47.322] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:47.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:47:0) after 1m0s
[15:53:47.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:47:0)
[15:53:47.365] Timer tick, asking Buildbucket for the build status
[15:53:47.533] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:47.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:48:0) after 1m0s
[15:54:47.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:48:0)
[15:54:47.625] Timer tick, asking Buildbucket for the build status
[15:54:47.731] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:47.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:49:0) after 1m0s
[15:55:47.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:49:0)
[15:55:47.716] Timer tick, asking Buildbucket for the build status
[15:55:47.889] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:47.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:50:0) after 1m0s
[15:56:47.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:50:0)
[15:56:47.960] Timer tick, asking Buildbucket for the build status
[15:56:48.101] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:48.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:51:0) after 1m0s
[15:57:48.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:51:0)
[15:57:48.284] Timer tick, asking Buildbucket for the build status
[15:57:48.398] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:48.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:52:0) after 1m0s
[15:58:48.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:52:0)
[15:58:48.519] Timer tick, asking Buildbucket for the build status
[15:58:48.652] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:48.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:53:0) after 1m0s
[15:59:48.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:53:0)
[15:59:48.658] Timer tick, asking Buildbucket for the build status
[15:59:48.812] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:48.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:54:0) after 1m0s
[16:00:48.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:54:0)
[16:00:48.917] Timer tick, asking Buildbucket for the build status
[16:00:49.088] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:49.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:55:0) after 1m0s
[16:01:49.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:55:0)
[16:01:49.157] Timer tick, asking Buildbucket for the build status
[16:01:49.343] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:49.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:56:0) after 1m0s
[16:02:49.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:56:0)
[16:02:49.408] Timer tick, asking Buildbucket for the build status
[16:02:49.503] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:49.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:57:0) after 1m0s
[16:03:49.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:57:0)
[16:03:49.585] Timer tick, asking Buildbucket for the build status
[16:03:49.729] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:49.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:58:0) after 1m0s
[16:04:49.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:58:0)
[16:04:49.807] Timer tick, asking Buildbucket for the build status
[16:04:50.511] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:50.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:59:0) after 1m0s
[16:05:50.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:59:0)
[16:05:50.548] Timer tick, asking Buildbucket for the build status
[16:05:50.912] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:50.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:60:0) after 1m0s
[16:06:50.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:60:0)
[16:06:50.943] Timer tick, asking Buildbucket for the build status
[16:06:51.037] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:51.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:61:0) after 1m0s
[16:07:51.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:61:0)
[16:07:51.077] Timer tick, asking Buildbucket for the build status
[16:07:51.435] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:51.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:62:0) after 1m0s
[16:08:51.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:62:0)
[16:08:51.495] Timer tick, asking Buildbucket for the build status
[16:08:51.618] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:51.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:63:0) after 1m0s
[16:09:51.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:63:0)
[16:09:51.622] Timer tick, asking Buildbucket for the build status
[16:09:51.890] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:51.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:64:0) after 1m0s
[16:10:51.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:64:0)
[16:10:51.936] Timer tick, asking Buildbucket for the build status
[16:10:52.039] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:52.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:65:0) after 1m0s
[16:11:52.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:65:0)
[16:11:52.066] Timer tick, asking Buildbucket for the build status
[16:11:52.132] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:52.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:66:0) after 1m0s
[16:12:52.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:66:0)
[16:12:52.153] Timer tick, asking Buildbucket for the build status
[16:12:52.295] Build 8863735526926380800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:52.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029165589224757328:67:0) after 1m0s
[16:13:24.344] Received PubSub notification, asking Buildbucket for the build status
[16:13:24.849] Build 8863735526926380800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:13:24.849] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1605284002062936",
"created_by": "project:chromium",
"created_ts": "1605280102760235",
"id": "8863735526926380800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9610fc68fc6a584bfabb132a32860e0ef5443fd7\"}}",
"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\": \"9610fc68fc6a584bfabb132a32860e0ef5443fd7\", \"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-5-d467\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15621, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"67eb26054d84566d477cd05ab0b4dcccc8a8ca48\", \"got_dawn_revision\": \"b6f4d53126d3f0ad522df5fc77da4ac919ea43f6\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9610fc68fc6a584bfabb132a32860e0ef5443fd7\", \"got_revision_cp\": \"refs/heads/master@{#827254}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"180faebe88270254d541b9324f855a50ed46af2c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32602}\", \"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\": \"9610fc68fc6a584bfabb132a32860e0ef5443fd7\", \"swarm_hashes_refs/heads/master(at){#827254}_without_patch\": {\"absl_hardening_tests\": \"6180f99da018925480be05c211f024f58eec58e2\", \"accessibility_unittests\": \"9565e40b3df50e4e04d1b7eba5b91095027c05fd\", \"angle_unittests\": \"e61ac0930848afc75b68ef6dfb198e0f1ff3ab24\", \"app_shell_unittests\": \"d3879842aabce88140be9713dbf5680192fcc407\", \"aura_unittests\": \"c6641eb5ffae0079b68f16a229f4766d762665ad\", \"base_unittests\": \"250cbe02364eb90633fbe526febb4613a21985bc\", \"base_util_unittests\": \"ebfb2a86efef6f9f1cadc0902d32d814c6043663\", \"blink_common_unittests\": \"df11fbd26f97be6122e2b5d3253240b08456d3c2\", \"blink_fuzzer_unittests\": \"a517321477ea960d1bcaa29ab3cdc39fbfd541c7\", \"blink_heap_unittests\": \"8cbf73a6d768d9734d090801ae4df920bc2b5e04\", \"blink_platform_unittests\": \"053e794663b1aafbf07ee7db99a5d9232464f4e8\", \"blink_unittests\": \"be648ae5d4546ea82dd6f4a4b3048b56ea2f824d\", \"boringssl_crypto_tests\": \"ae7fad4a4800fcfc9a04759d36fb35050f3f87c2\", \"boringssl_ssl_tests\": \"6354260b844b80184476b985d27b1e4850b01d5d\", \"browser_switcher_bho_unittests\": \"638aeb897292bff8193e4039c1a778aac9b6f7b0\", \"browser_tests\": \"9ab3884a396f673b3542e91be84b59ed00b23625\", \"capture_unittests\": \"3edf11bb9210b6d5c043a4259b3a04109795b158\", \"cast_unittests\": \"22e54c2497c300302f6f036464fe6a19463db652\", \"cc_unittests\": \"6b11198d47429f02f51dcba8f97c994a21e387d2\", \"chrome_app_unittests\": \"04a2afdde94f476732c130c711392e2d1cd266c2\", \"chrome_cleaner_unittests\": \"d7c9f9d732f31f260f0e97c2b3eff8433acd1e69\", \"chrome_elf_unittests\": \"235cddcd033afae16545cb8c9b689d21b99f6574\", \"chromedriver_unittests\": \"77e49159788e82b31c4d0580de1a2a33cc864395\", \"components_browsertests\": \"d2ed00bcc61b7387ce1fbd97e4d0108d1d511634\", \"components_unittests\": \"dce405a0c19f26adbca8f0799625bd266cb170b3\", \"compositor_unittests\": \"e8f505fb22b13c46da8a70e6d30f0ad75e56f77b\", \"content_browsertests\": \"5612749775a973c4ba355149ccea1004c2afcab5\", \"content_unittests\": \"55b53eac41a5443f5d9fd371987a3d669105a7c0\", \"courgette_unittests\": \"8970e6d0f54ae4e531c24dc0f858b353f6389e32\", \"crashpad_tests\": \"0f1040734d10ace10cbde3731282d013b481b3f1\", \"cronet_tests\": \"040ad1d3c89bcebccf7583b15fca9a30bb550ef1\", \"cronet_unittests\": \"ae17ee071956591d2d1b9c0cf87ec2838e9d0620\", \"crypto_unittests\": \"8a3f804aac183aa2fcaedb27863c1017021bdaa0\", \"delayloads_unittests\": \"a8230635a1ed484d2d16d57824cc19aebb78d2dc\", \"device_unittests\": \"e17f9c1bb835050467ce50334422330d967fc054\", \"display_unittests\": \"10e9b1b86e970a861ddf484520d15fa862c97e84\", \"elevation_service_unittests\": \"9aaae0a47ed4170b73a0f92208e5ae81f5732d05\", \"events_unittests\": \"a4bd0a1b22b80748e217178c270abfc629b19058\", \"extensions_browsertests\": \"fc1e69e362dad77afd76a0ad9217c55120991695\", \"extensions_unittests\": \"c006858871e65cd57f594e5dc2e79aeb29157996\", \"filesystem_service_unittests\": \"7228905b3ac32dee6114b15468566765426b2fda\", \"gcm_unit_tests\": \"3671bd52f035da077ffec8c3fc0d678fed417613\", \"gcp_unittests\": \"27de79f57b35a33f398a9d6061bbbb3ed346ff54\", \"gfx_unittests\": \"387ece843ef301ebc7618885e5e8f255bf7b6a48\", \"gin_unittests\": \"1750dbf9d7fc7749cd53f851341f4536ea1f98d4\", \"google_apis_unittests\": \"391216bc86f4c68b0996e56b59b7058340de649f\", \"gpu_unittests\": \"87309933d53a252a8b7f869e3eff8267c2142429\", \"gwp_asan_unittests\": \"aad34b6ee2eb2fa83b4c13dce10f5f4da7d2e344\", \"headless_browsertests\": \"67d3367787f2097fbba1e48b4e3d1f89725a1d79\", \"headless_unittests\": \"3856aa31b02d64cdf109f7cdf65a8e38056973c4\", \"install_static_unittests\": \"2c12711e8af82ff67fe3eadd443acc2049f71e4b\", \"installer_util_unittests\": \"5555d5d7bb3aa462e46748c99956508160d311a1\", \"interactive_ui_tests\": \"125a2bbbd3812bad259e79b4ded7b5a83b8b7a09\", \"ipc_tests\": \"d4ce25766a854ba1f5ec802e1e27b8443c2083ad\", \"jingle_unittests\": \"2cf5a988524d4ae64ef7b633162dfcff12d10756\", \"latency_unittests\": \"62844ae7dff019c2bbd74e077d37a81112dfcc87\", \"libjingle_xmpp_unittests\": \"a944b215dc127d704dd8e684bdc786474ad320a4\", \"liburlpattern_unittests\": \"55571ca7071eb52aea8d82e51c72faf167667cbd\", \"media_blink_unittests\": \"ab9880227be9d116a39327635314b8ff96a544ea\", \"media_unittests\": \"1c276b857bcbd402c0c06a793a0f75041b82de16\", \"message_center_unittests\": \"f1ccce6505bce804df3f6f65785e3840de4d6eec\", \"midi_unittests\": \"8b885eaec52219e0a0a35051f1a942fc0a7e886d\", \"mojo_core_unittests\": \"134c049d247ab07a5fe1b6968f8370a159d7beca\", \"mojo_unittests\": \"6543b54a29ad99a26cd0541b2367ccb7353b15b1\", \"nacl_loader_unittests\": \"a70cbe5f581dc01d7101be309313474b01818703\", \"native_theme_unittests\": \"28051d441b0896b16719ae353881299082564845\", \"net_unittests\": \"6022ce68f3a7de0c126edbf9dd17eb50e08daee0\", \"notification_helper_unittests\": \"a4dc04dfdc47b6a13d096e8599968936a1758b1d\", \"pdf_unittests\": \"dc698ade239ac9e8cc2cc74c52a7a069f4ea9739\", \"perfetto_unittests\": \"371f897b5f05ceb697918cbd3f06a00d76ba9628\", \"ppapi_unittests\": \"8acdc466497d27d9675b8d5046ad00f500106d6a\", \"printing_unittests\": \"94832f7a8c23da42b080a85872012c5fe41c1012\", \"remoting_unittests\": \"4634075a46e78a657de25eb7ea72e20176c8f952\", \"sbox_integration_tests\": \"09852ef266cf2f9745842d66be082111a59849be\", \"sbox_unittests\": \"daed6542d4861efc865cf84ba9d839f54e3bb5d9\", \"sbox_validation_tests\": \"4eeb820b83b14f69f8e92a75923abc5c30bc5750\", \"service_manager_unittests\": \"523a6d2c59ded1c33370f25e0d3b50d9c4e70bc0\", \"services_unittests\": \"eae54000b9f986c807e1e46de928487d7cf8a493\", \"setup_unittests\": \"2f29251cb78c0330b03df10f9068a9508ac47e8e\", \"shell_dialogs_unittests\": \"5e7505038f8d919a698ea393954c1d0216ddf0c6\", \"skia_unittests\": \"7bc1716ff1002ada7a30369b4ce914cb22d69baa\", \"snapshot_unittests\": \"eff07688fcff2b358f23807742af26e7c78fb7e5\", \"sql_unittests\": \"3f29c136ab08018791978c60925698ef834c19b2\", \"storage_unittests\": \"eac6f881377de1028d69433110b063db21abee53\", \"sync_integration_tests\": \"53ee8571b5300ed3ed321e1530ff806f7685a2bc\", \"traffic_annotation_auditor_unittests\": \"27f6dd7a897c93a62115729f71089e9bea6c1f88\", \"ui_base_unittests\": \"151b3567222730a69bc26d7203d5e6767c212527\", \"ui_touch_selection_unittests\": \"7315e477fa16a37ce7f4399facc6df8f5b4ca3c2\", \"unit_tests\": \"a30bfb3731aeaab10f4ad82c799a383aa59dc075\", \"updater_tests\": \"90c5eb204776abc75bc17cb436d5eb0e991cf433\", \"url_unittests\": \"fe33f61923b6740672c8b917cc41e0df6b7d799d\", \"views_unittests\": \"9c7907e1d0132727a74375f98578637c70b451f6\", \"viz_unittests\": \"a912cdcf52c931ba9444f1c0d3529b65cb42de56\", \"vr_common_unittests\": \"224ded6afc3de3b3c53d561a97340dcee509d8f0\", \"vr_pixeltests\": \"3f502bd581dbc6b285303cb7a865d799eb4ab938\", \"weblayer_browsertests\": \"25caccadebba230dd449b47823ec0f179c366bfc\", \"weblayer_unittests\": \"c6a0c1a14031f38a204b761f608c294eb7c232f4\", \"wm_unittests\": \"0addd50557755472aec97904aa6ac7cbc87587b1\", \"wtf_unittests\": \"8b7d9463e7a2aa2befc617ee47ff062a1a3a0d16\", \"zlib_unittests\": \"80eeb7390c0581eb90055b1a2a81fbe4b54f18dc\", \"zucchini_unittests\": \"c3e783d96b09550e9b13bae24d2079075fdbb5fb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-d467\"], \"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\": [\"5626-39642e9\"], \"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": "1605280105174241",
"status": "COMPLETED",
"status_changed_ts": "1605284002584322",
"tags": [
"build_address:luci.chromium.ci/win-asan/15621",
"builder:win-asan",
"buildset:commit/git/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9610fc68fc6a584bfabb132a32860e0ef5443fd7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029165589224757328",
"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/8863735526926380800/+/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:4fdaf805ff017010",
"user_agent:luci-scheduler"
],
"updated_ts": "1605284002628868",
"url": "https://ci.chromium.org/b/8863735526926380800",
"utcnow_ts": "1605284004835260"
}
}
[16:13:24.849] Invocation finished in 1h5m3.663474429s with status SUCCEEDED