[21:10:42.669] New invocation is queued and will start shortly
[21:10:43.866] Starting the invocation (attempt 1)
[21:10:43.925] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:10:43.925] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:10:43.925] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029505179529070752",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"31dd6c13f368cb49f0d1979b353a9858ae8ed379\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029505179529070752",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"gitiles_ref:refs/heads/master"
]
}
[21:10:44.533] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604956244037607",
"id": "8864075117411404864",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31dd6c13f368cb49f0d1979b353a9858ae8ed379\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604956244422188",
"tags": [
"build_address:luci.chromium.ci/win-asan/15560",
"builder:win-asan",
"buildset:commit/git/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029505179529070752",
"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/8864075117411404864/+/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": "1604956244422345",
"url": "https://ci.chromium.org/b/8864075117411404864",
"utcnow_ts": "1604956244524640"
}
}
[21:10:44.533] Task URL: https://ci.chromium.org/b/8864075117411404864
[21:10:44.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:2:0) after 1m0s
[21:10:49.411] Received PubSub notification, asking Buildbucket for the build status
[21:10:49.574] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:44.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:2:0)
[21:11:44.647] Timer tick, asking Buildbucket for the build status
[21:11:44.821] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:44.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:4:0) after 1m0s
[21:12:44.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:4:0)
[21:12:44.957] Timer tick, asking Buildbucket for the build status
[21:12:45.183] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:45.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:5:0) after 1m0s
[21:13:45.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:5:0)
[21:13:45.379] Timer tick, asking Buildbucket for the build status
[21:13:45.501] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:45.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:6:0) after 1m0s
[21:14:45.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:6:0)
[21:14:45.604] Timer tick, asking Buildbucket for the build status
[21:14:45.811] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:45.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:7:0) after 1m0s
[21:15:45.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:7:0)
[21:15:45.908] Timer tick, asking Buildbucket for the build status
[21:15:45.997] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:45.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:8:0) after 1m0s
[21:16:46.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:8:0)
[21:16:46.098] Timer tick, asking Buildbucket for the build status
[21:16:46.221] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:46.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:9:0) after 1m0s
[21:17:46.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:9:0)
[21:17:46.299] Timer tick, asking Buildbucket for the build status
[21:17:46.453] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:46.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:10:0) after 1m0s
[21:18:46.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:10:0)
[21:18:46.521] Timer tick, asking Buildbucket for the build status
[21:18:46.700] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:46.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:11:0) after 1m0s
[21:19:46.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:11:0)
[21:19:46.917] Timer tick, asking Buildbucket for the build status
[21:19:46.980] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:46.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:12:0) after 1m0s
[21:20:47.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:12:0)
[21:20:47.124] Timer tick, asking Buildbucket for the build status
[21:20:47.264] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:47.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:13:0) after 1m0s
[21:21:47.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:13:0)
[21:21:47.467] Timer tick, asking Buildbucket for the build status
[21:21:47.695] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:47.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:14:0) after 1m0s
[21:22:47.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:14:0)
[21:22:47.815] Timer tick, asking Buildbucket for the build status
[21:22:48.190] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:48.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:15:0) after 1m0s
[21:23:48.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:15:0)
[21:23:48.219] Timer tick, asking Buildbucket for the build status
[21:23:50.006] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:50.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:16:0) after 1m0s
[21:24:50.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:16:0)
[21:24:50.161] Timer tick, asking Buildbucket for the build status
[21:24:50.238] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:50.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:17:0) after 1m0s
[21:25:50.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:17:0)
[21:25:50.294] Timer tick, asking Buildbucket for the build status
[21:25:50.419] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:50.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:18:0) after 1m0s
[21:26:50.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:18:0)
[21:26:50.533] Timer tick, asking Buildbucket for the build status
[21:26:50.598] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:50.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:19:0) after 1m0s
[21:27:50.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:19:0)
[21:27:50.791] Timer tick, asking Buildbucket for the build status
[21:27:55.122] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:55.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:20:0) after 1m0s
[21:28:55.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:20:0)
[21:28:55.309] Timer tick, asking Buildbucket for the build status
[21:28:55.880] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:55.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:21:0) after 1m0s
[21:29:55.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:21:0)
[21:29:55.915] Timer tick, asking Buildbucket for the build status
[21:29:56.005] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:56.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:22:0) after 1m0s
[21:30:56.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:22:0)
[21:30:56.131] Timer tick, asking Buildbucket for the build status
[21:30:56.378] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:56.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:23:0) after 1m0s
[21:31:56.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:23:0)
[21:31:56.517] Timer tick, asking Buildbucket for the build status
[21:31:56.655] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:56.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:24:0) after 1m0s
[21:32:56.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:24:0)
[21:32:56.677] Timer tick, asking Buildbucket for the build status
[21:32:57.254] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:57.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:25:0) after 1m0s
[21:33:57.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:25:0)
[21:33:57.322] Timer tick, asking Buildbucket for the build status
[21:33:57.404] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:26:0) after 1m0s
[21:34:57.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:26:0)
[21:34:57.460] Timer tick, asking Buildbucket for the build status
[21:34:59.684] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:59.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:27:0) after 1m0s
[21:35:59.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:27:0)
[21:35:59.880] Timer tick, asking Buildbucket for the build status
[21:36:00.196] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:00.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:28:0) after 1m0s
[21:37:01.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:28:0)
[21:37:01.196] Timer tick, asking Buildbucket for the build status
[21:37:01.342] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:01.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:29:0) after 1m0s
[21:38:01.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:29:0)
[21:38:01.439] Timer tick, asking Buildbucket for the build status
[21:38:01.565] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:01.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:30:0) after 1m0s
[21:39:01.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:30:0)
[21:39:01.641] Timer tick, asking Buildbucket for the build status
[21:39:02.002] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:02.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:31:0) after 1m0s
[21:40:02.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:31:0)
[21:40:02.725] Timer tick, asking Buildbucket for the build status
[21:40:02.959] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:02.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:32:0) after 1m0s
[21:41:02.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:32:0)
[21:41:02.978] Timer tick, asking Buildbucket for the build status
[21:41:03.207] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:03.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:33:0) after 1m0s
[21:42:03.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:33:0)
[21:42:03.700] Timer tick, asking Buildbucket for the build status
[21:42:03.936] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:03.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:34:0) after 1m0s
[21:43:04.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:34:0)
[21:43:04.033] Timer tick, asking Buildbucket for the build status
[21:43:04.152] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:04.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:35:0) after 1m0s
[21:44:04.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:35:0)
[21:44:04.316] Timer tick, asking Buildbucket for the build status
[21:44:04.467] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:04.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:36:0) after 1m0s
[21:45:04.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:36:0)
[21:45:04.485] Timer tick, asking Buildbucket for the build status
[21:45:05.263] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:05.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:37:0) after 1m0s
[21:46:06.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:37:0)
[21:46:06.296] Timer tick, asking Buildbucket for the build status
[21:46:06.466] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:06.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:38:0) after 1m0s
[21:47:06.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:38:0)
[21:47:06.512] Timer tick, asking Buildbucket for the build status
[21:47:06.742] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:06.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:39:0) after 1m0s
[21:48:06.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:39:0)
[21:48:06.851] Timer tick, asking Buildbucket for the build status
[21:48:07.047] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:07.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:40:0) after 1m0s
[21:49:07.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:40:0)
[21:49:07.198] Timer tick, asking Buildbucket for the build status
[21:49:07.709] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:07.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:41:0) after 1m0s
[21:50:07.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:41:0)
[21:50:07.853] Timer tick, asking Buildbucket for the build status
[21:50:08.224] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:08.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:42:0) after 1m0s
[21:51:08.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:42:0)
[21:51:08.274] Timer tick, asking Buildbucket for the build status
[21:51:08.416] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:08.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:43:0) after 1m0s
[21:52:08.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:43:0)
[21:52:08.446] Timer tick, asking Buildbucket for the build status
[21:52:08.566] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:08.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:44:0) after 1m0s
[21:53:08.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:44:0)
[21:53:08.623] Timer tick, asking Buildbucket for the build status
[21:53:08.810] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:45:0) after 1m0s
[21:54:08.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:45:0)
[21:54:08.833] Timer tick, asking Buildbucket for the build status
[21:54:10.917] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:10.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:46:0) after 1m0s
[21:55:11.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:46:0)
[21:55:11.027] Timer tick, asking Buildbucket for the build status
[21:55:11.116] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:11.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:47:0) after 1m0s
[21:56:11.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:47:0)
[21:56:11.223] Timer tick, asking Buildbucket for the build status
[21:56:11.366] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:11.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:48:0) after 1m0s
[21:57:11.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:48:0)
[21:57:11.467] Timer tick, asking Buildbucket for the build status
[21:57:11.571] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:11.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:49:0) after 1m0s
[21:58:11.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:49:0)
[21:58:11.834] Timer tick, asking Buildbucket for the build status
[21:58:11.954] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:50:0) after 1m0s
[21:59:12.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:50:0)
[21:59:12.168] Timer tick, asking Buildbucket for the build status
[21:59:12.287] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:12.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:51:0) after 1m0s
[22:00:12.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:51:0)
[22:00:12.429] Timer tick, asking Buildbucket for the build status
[22:00:12.845] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:52:0) after 1m0s
[22:01:12.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:52:0)
[22:01:12.911] Timer tick, asking Buildbucket for the build status
[22:01:13.396] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:53:0) after 1m0s
[22:02:13.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:53:0)
[22:02:13.418] Timer tick, asking Buildbucket for the build status
[22:02:13.641] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:13.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:54:0) after 1m0s
[22:03:13.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:54:0)
[22:03:13.809] Timer tick, asking Buildbucket for the build status
[22:03:14.314] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:14.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:55:0) after 1m0s
[22:04:14.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:55:0)
[22:04:14.485] Timer tick, asking Buildbucket for the build status
[22:04:14.789] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:14.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:56:0) after 1m0s
[22:05:14.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:56:0)
[22:05:14.968] Timer tick, asking Buildbucket for the build status
[22:05:15.117] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:57:0) after 1m0s
[22:06:15.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:57:0)
[22:06:15.246] Timer tick, asking Buildbucket for the build status
[22:06:15.629] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:15.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:58:0) after 1m0s
[22:07:15.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:58:0)
[22:07:15.796] Timer tick, asking Buildbucket for the build status
[22:07:15.881] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:15.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:59:0) after 1m0s
[22:08:16.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:59:0)
[22:08:16.003] Timer tick, asking Buildbucket for the build status
[22:08:16.199] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:16.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:60:0) after 1m0s
[22:09:16.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:60:0)
[22:09:16.320] Timer tick, asking Buildbucket for the build status
[22:09:16.476] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:16.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:61:0) after 1m0s
[22:10:16.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:61:0)
[22:10:16.571] Timer tick, asking Buildbucket for the build status
[22:10:17.027] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:17.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:62:0) after 1m0s
[22:11:17.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:62:0)
[22:11:17.129] Timer tick, asking Buildbucket for the build status
[22:11:17.743] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:17.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:63:0) after 1m0s
[22:12:17.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:63:0)
[22:12:17.813] Timer tick, asking Buildbucket for the build status
[22:12:17.917] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:17.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:64:0) after 1m0s
[22:13:18.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:64:0)
[22:13:18.007] Timer tick, asking Buildbucket for the build status
[22:13:18.158] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:18.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:65:0) after 1m0s
[22:14:18.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:65:0)
[22:14:18.214] Timer tick, asking Buildbucket for the build status
[22:14:18.320] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:18.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:66:0) after 1m0s
[22:15:18.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:66:0)
[22:15:18.499] Timer tick, asking Buildbucket for the build status
[22:15:18.628] Build 8864075117411404864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:18.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029505179529070752:67:0) after 1m0s
[22:16:05.920] Received PubSub notification, asking Buildbucket for the build status
[22:16:06.112] Build 8864075117411404864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:16:06.112] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604960163068556",
"created_by": "project:chromium",
"created_ts": "1604956244037607",
"id": "8864075117411404864",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31dd6c13f368cb49f0d1979b353a9858ae8ed379\"}}",
"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\": \"31dd6c13f368cb49f0d1979b353a9858ae8ed379\", \"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-8-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15560, \"clang_revision\": \"llvmorg-12-init-11060-g118c3f3c-1\", \"got_angle_revision\": \"9217d49db032476b003472b539b5c76fff234be1\", \"got_dawn_revision\": \"0053fef8a47d72a1a240df80395d5a4bf9f6170c\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"31dd6c13f368cb49f0d1979b353a9858ae8ed379\", \"got_revision_cp\": \"refs/heads/master@{#825462}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"b4a39160e51f21c446e5b042ae88603ea1f7b7d8\", \"got_v8_revision_cp\": \"refs/heads/8.8.262@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"31dd6c13f368cb49f0d1979b353a9858ae8ed379\", \"swarm_hashes_refs/heads/master(at){#825462}_without_patch\": {\"absl_hardening_tests\": \"bb98f9756b5d221fa77df1da4384a8c8a148b97c\", \"accessibility_unittests\": \"af06da213c3b0e93a6666e702fe2ab78420f3061\", \"angle_unittests\": \"2fef1bfd05d4e625b8229c99a5a053dd0dcc53a2\", \"app_shell_unittests\": \"ae6275a7fcd5fa53e1c533e732320bf3185e2cab\", \"aura_unittests\": \"3d39cad1ac4402e1b37bfd1cbead0b5e50e34376\", \"base_unittests\": \"0d5da09041cb3ed8d9f7f75c189e9175baf5d763\", \"base_util_unittests\": \"c81d8e93730033df1f101c99f2bc601d98821c67\", \"blink_common_unittests\": \"973d8c9be10320bb606bfd8fea6249da08d5ae71\", \"blink_fuzzer_unittests\": \"ed05277d47c668768bafa982c3dfbc5c26c86e71\", \"blink_heap_unittests\": \"ff03cee574f1b291719e7335992c1e6b8813a6ff\", \"blink_platform_unittests\": \"222d759cc10be6e505c2748381f5ce85d2f2d281\", \"blink_unittests\": \"74ba03ed963051259bf4d3f0d981b695321a98af\", \"boringssl_crypto_tests\": \"fa0eada8623a87a911a087c1d01f7b29bad9f30a\", \"boringssl_ssl_tests\": \"89639dc90737f8b721e7579e45ed93446d78e7ca\", \"browser_switcher_bho_unittests\": \"d379f5e09529ddc3de6314f3237951a69baaf5f0\", \"browser_tests\": \"56677fbfa5cc9646759b7dc6c1381a7b8b2e60f7\", \"capture_unittests\": \"7f86d48b536f03f2fb74af42f793fcd162cc6ff5\", \"cast_unittests\": \"1f4900516d41e5b2ddeeed4739c0417ce4f6aa7b\", \"cc_unittests\": \"a9c0167a00b23d585234bd63349320edb04fb47f\", \"chrome_app_unittests\": \"06788058e1a490087de9d9b51d910826500a6287\", \"chrome_cleaner_unittests\": \"53097acbb64d07e9430be87a68d0b59b34eee0f4\", \"chrome_elf_unittests\": \"67f8f762dffa423330c0f713de3619d6652627f6\", \"chromedriver_unittests\": \"e160f2f262ee0939ff7c9f01d9f53896e276cc8f\", \"components_browsertests\": \"1b88fb5fb67478f58319ad3bca528c6a213f4666\", \"components_unittests\": \"a411c60ecc6bca8dd833da6244d6a4e74297c842\", \"compositor_unittests\": \"8810477d04923198431319b82c0dfd10c7e8145f\", \"content_browsertests\": \"1c4fd75b72cdf9f668c0e8befbd98492c37b7242\", \"content_unittests\": \"d835ab66f283ba90a9dd9425cde765ce707231a2\", \"courgette_unittests\": \"04d3a3e7906b25a9f4c07ad52106c260f314f2bc\", \"crashpad_tests\": \"eee804fe41e2256de003015e943ba8cc6d40b125\", \"cronet_tests\": \"e13ad363bbbed752d909421f8183ee0c071e7f13\", \"cronet_unittests\": \"a8db13bae8364af89763ad58b9df1a82f881ee3f\", \"crypto_unittests\": \"2a9332701c29d5f54a18b57d6a505f819ea57885\", \"delayloads_unittests\": \"cb60711dcf1fa438a6a39fc786971f6d6f966012\", \"device_unittests\": \"583f1658db5ca4c4511fa26967edeac3c5b3fea8\", \"display_unittests\": \"c7bd7a18d6044bf198d68b23f40584bdf1af0be2\", \"elevation_service_unittests\": \"ffaea8b415cdd79a8af59fe50e54131fec86fc2e\", \"events_unittests\": \"bbf5df2f2a44efcd8f50965710e45972020f7833\", \"extensions_browsertests\": \"fc3da1ef1513eaca396945ff898c49bf52cd4209\", \"extensions_unittests\": \"b3abfb3c57ca25e53ab2a39eaf6b1926d29f6bf4\", \"filesystem_service_unittests\": \"b0c5a7027ad1ed1a20c9f848944f07479698e121\", \"gcm_unit_tests\": \"463920532483911168b34d2f906af4e9cf4f31a7\", \"gcp_unittests\": \"b2c5c6429f31cf9981b710fab09a65f9e1eff742\", \"gfx_unittests\": \"e5f5f31864e69415a0895159e19cd5f9222fe7cf\", \"gin_unittests\": \"d79469ca32ec474577e6300e92dbd1e3b9a43f69\", \"google_apis_unittests\": \"37cb3d5b56eba8df057b6e0479d7055eb62dccef\", \"gpu_unittests\": \"d700c39bfe1cda5a08951e266d3fd82ec38ae936\", \"gwp_asan_unittests\": \"8be87148424d7c441e1149ea70682d63f6b11f31\", \"headless_browsertests\": \"023a46b576f33370bfa762f36e174cac8baaf5d2\", \"headless_unittests\": \"9137b78917a756c7fb78c839cacc66e0e0450a28\", \"install_static_unittests\": \"dc69be8bd2c1ee9022c250f1b2cc9ede72aae893\", \"installer_util_unittests\": \"84f8e06b7c13e735b44cb263053fba2bbd3ce0be\", \"interactive_ui_tests\": \"a4b8440b70fe0534aaea4e0f8ee59317c5eee825\", \"ipc_tests\": \"b965845426148aa61b90a35528ac6820ef8cd633\", \"jingle_unittests\": \"9fad34e4e568c30c34f6ba6d6769322bff921707\", \"latency_unittests\": \"4b5863a340c547b32d3bfd3b171fef05d2e1f1a3\", \"libjingle_xmpp_unittests\": \"668873eb95e0253a95ae08433024d204ce8880a7\", \"liburlpattern_unittests\": \"238e32a9f6cb2d1bc58223e56512341bec44a4ff\", \"media_blink_unittests\": \"a49e1c0c9354447018b33fa872799124d74f7d60\", \"media_unittests\": \"29330290b198852210f96f4dd9251eff9e6d6380\", \"message_center_unittests\": \"9e81a4eef4072662ad4b58c0288b1f4d22c11f7e\", \"midi_unittests\": \"690982c35ff68ae93a77c960ea31459182af2cc1\", \"mojo_core_unittests\": \"be801c8801e69230c21f5580d72a220103fa4176\", \"mojo_unittests\": \"376d29d67f2074d10e6f0e9ad1bf3d9cd5c4875a\", \"nacl_loader_unittests\": \"7b8ac727b871e0327cf6d2c5c1ab8635824cca47\", \"native_theme_unittests\": \"2ad5fa070379b97b6fb3e67f8d76e67ed989222a\", \"net_unittests\": \"ca66b31e46de5dee45a1b30597dae2b3beab1ca8\", \"notification_helper_unittests\": \"0f9c3652f9a46c58902b352f4b4c75d3be3f4eda\", \"pdf_unittests\": \"2d1190839f1305c72773cbe7d803b69ebf3a7082\", \"perfetto_unittests\": \"3c326f46d3a280b5c7347ae317b06b897f8773c2\", \"ppapi_unittests\": \"8e0ef9cce6b1d6ae637ac54e5199c24b91b48643\", \"printing_unittests\": \"20ffa623cfc6c92e1668dca571c7c3456522199a\", \"remoting_unittests\": \"e7fe816bf20b1af1c017dea014499a36d7ca3a47\", \"sbox_integration_tests\": \"f02fa13e873fd171e5a5e880ddf7b673604a829e\", \"sbox_unittests\": \"c4bfa1fcb630f9febece6cfb7a426ad01c94b80d\", \"sbox_validation_tests\": \"4b723b6a8269240848f12f34b21f222790b7b09a\", \"service_manager_unittests\": \"aa63363da10871a06db1db19f9218523e63400ca\", \"services_unittests\": \"3ecf48e715cab1f5def841859219afe0f54d2995\", \"setup_unittests\": \"14cae4cd6790a3a7eca04050edfbf48c3fc9fd9b\", \"shell_dialogs_unittests\": \"7820cd20dcb29838e865b4afbcc7016d7014b04d\", \"skia_unittests\": \"5f11c205f63f2db181875c22a73c89b689e14769\", \"snapshot_unittests\": \"b4473c85e573a3a145eaaaaf60772f92c0fb1497\", \"sql_unittests\": \"25125c132f3c3677c96881762213beaa221a98fd\", \"storage_unittests\": \"65d873838a37daa8dd17ff2f5084738a7eb3780f\", \"sync_integration_tests\": \"28bacf622eedd112839b9504b983a32043fed437\", \"traffic_annotation_auditor_unittests\": \"4db552d45ae977b4c994934bf35c13a4140035f7\", \"ui_base_unittests\": \"476ff22e0d7bf50c4d3f1f05a6cf4680aa072203\", \"ui_touch_selection_unittests\": \"839f01d6e2a336217eaf4f9cc712b66388d74549\", \"unit_tests\": \"586aa8dcfca0d1a3936be55451094645581f9594\", \"updater_tests\": \"f886d507ae84467a9cc3579def57d7171dfdf48a\", \"url_unittests\": \"13d234693f53f7173c5992c8b825aa22ba448175\", \"views_unittests\": \"e2404e25218656a32673d15e8e710e5518c9a683\", \"viz_unittests\": \"db33d99f6e9d4d3a395d33d60418dd44ffea1bc4\", \"vr_common_unittests\": \"f9c7744e41132e1a5d95a0f4aff306224f632937\", \"vr_pixeltests\": \"16f5317cefad00fbf58049a7942a1a3d12a33b0b\", \"weblayer_browsertests\": \"35fc67b68f1ac125efe08c6cd895bc33549dbeea\", \"weblayer_unittests\": \"c1ededa9d583d3fe992058adbabc29957efe1685\", \"wm_unittests\": \"05edf0d9591c93c189218dee583a2523057247ae\", \"wtf_unittests\": \"5022a70c00963413513852ac02e4e057c248c383\", \"zlib_unittests\": \"635c6d419d32ac4464efceb833d93f0548f918e4\", \"zucchini_unittests\": \"4efb024d8b3dbefc5d4da5c92763089b7c092554\"}}, \"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-8-b9eu\"], \"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": "1604956248071333",
"status": "COMPLETED",
"status_changed_ts": "1604960163943645",
"tags": [
"build_address:luci.chromium.ci/win-asan/15560",
"builder:win-asan",
"buildset:commit/git/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31dd6c13f368cb49f0d1979b353a9858ae8ed379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029505179529070752",
"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/8864075117411404864/+/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:4fc7aa53dbc59210",
"user_agent:luci-scheduler"
],
"updated_ts": "1604960163998488",
"url": "https://ci.chromium.org/b/8864075117411404864",
"utcnow_ts": "1604960166097532"
}
}
[22:16:06.112] Invocation finished in 1h5m23.47858948s with status SUCCEEDED