[19:13:54.562] New invocation is queued and will start shortly
[19:13:55.825] Starting the invocation (attempt 1)
[19:13:55.865] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:13:55.866] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:13:55.867] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023080143427211344",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bbbc85892facf232e750126a892056fbe640044e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023080143427211344",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bbbc85892facf232e750126a892056fbe640044e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbbc85892facf232e750126a892056fbe640044e",
"gitiles_ref:refs/heads/master"
]
}
[19:13:57.127] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611083635962109",
"id": "8857650081295729824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbbc85892facf232e750126a892056fbe640044e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611083636675055",
"tags": [
"build_address:luci.chromium.ci/win-asan/16897",
"builder:win-asan",
"buildset:commit/git/bbbc85892facf232e750126a892056fbe640044e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbbc85892facf232e750126a892056fbe640044e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023080143427211344",
"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/8857650081295729824/+/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": "1611083636675258",
"url": "https://ci.chromium.org/b/8857650081295729824",
"utcnow_ts": "1611083637111670"
}
}
[19:13:57.127] Task URL: https://ci.chromium.org/b/8857650081295729824
[19:13:57.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:2:0) after 1m0s
[19:13:58.280] Received PubSub notification, asking Buildbucket for the build status
[19:13:58.660] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:57.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:2:0)
[19:14:57.165] Timer tick, asking Buildbucket for the build status
[19:14:57.327] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:57.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:4:0) after 1m0s
[19:15:57.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:4:0)
[19:15:57.348] Timer tick, asking Buildbucket for the build status
[19:15:57.636] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:57.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:5:0) after 1m0s
[19:16:57.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:5:0)
[19:16:57.715] Timer tick, asking Buildbucket for the build status
[19:16:57.838] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:57.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:6:0) after 1m0s
[19:17:57.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:6:0)
[19:17:57.841] Timer tick, asking Buildbucket for the build status
[19:17:57.985] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:57.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:7:0) after 1m0s
[19:18:58.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:7:0)
[19:18:58.014] Timer tick, asking Buildbucket for the build status
[19:18:58.137] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:58.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:8:0) after 1m0s
[19:19:58.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:8:0)
[19:19:58.158] Timer tick, asking Buildbucket for the build status
[19:19:58.249] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:58.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:9:0) after 1m0s
[19:20:58.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:9:0)
[19:20:58.636] Timer tick, asking Buildbucket for the build status
[19:20:58.719] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:58.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:10:0) after 1m0s
[19:21:58.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:10:0)
[19:21:58.919] Timer tick, asking Buildbucket for the build status
[19:21:59.000] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:59.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:11:0) after 1m0s
[19:22:59.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:11:0)
[19:22:59.033] Timer tick, asking Buildbucket for the build status
[19:22:59.173] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:59.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:12:0) after 1m0s
[19:23:59.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:12:0)
[19:23:59.334] Timer tick, asking Buildbucket for the build status
[19:23:59.472] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:59.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:13:0) after 1m0s
[19:24:59.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:13:0)
[19:24:59.628] Timer tick, asking Buildbucket for the build status
[19:24:59.707] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:59.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:14:0) after 1m0s
[19:25:59.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:14:0)
[19:25:59.809] Timer tick, asking Buildbucket for the build status
[19:25:59.886] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:59.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:15:0) after 1m0s
[19:26:59.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:15:0)
[19:26:59.943] Timer tick, asking Buildbucket for the build status
[19:27:00.303] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:00.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:16:0) after 1m0s
[19:28:00.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:16:0)
[19:28:00.345] Timer tick, asking Buildbucket for the build status
[19:28:00.604] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:00.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:17:0) after 1m0s
[19:29:01.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:17:0)
[19:29:01.452] Timer tick, asking Buildbucket for the build status
[19:29:01.603] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:18:0) after 1m0s
[19:30:01.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:18:0)
[19:30:01.619] Timer tick, asking Buildbucket for the build status
[19:30:02.022] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:02.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:19:0) after 1m0s
[19:31:02.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:19:0)
[19:31:02.054] Timer tick, asking Buildbucket for the build status
[19:31:02.240] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:02.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:20:0) after 1m0s
[19:32:02.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:20:0)
[19:32:02.290] Timer tick, asking Buildbucket for the build status
[19:32:02.481] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:02.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:21:0) after 1m0s
[19:33:02.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:21:0)
[19:33:02.542] Timer tick, asking Buildbucket for the build status
[19:33:02.663] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:02.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:22:0) after 1m0s
[19:34:02.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:22:0)
[19:34:02.645] Timer tick, asking Buildbucket for the build status
[19:34:03.032] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:03.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:23:0) after 1m0s
[19:35:03.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:23:0)
[19:35:03.070] Timer tick, asking Buildbucket for the build status
[19:35:03.757] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:03.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:24:0) after 1m0s
[19:36:03.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:24:0)
[19:36:03.810] Timer tick, asking Buildbucket for the build status
[19:36:04.033] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:04.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:25:0) after 1m0s
[19:37:04.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:25:0)
[19:37:04.148] Timer tick, asking Buildbucket for the build status
[19:37:05.005] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:05.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:26:0) after 1m0s
[19:38:05.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:26:0)
[19:38:05.097] Timer tick, asking Buildbucket for the build status
[19:38:05.560] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:05.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:27:0) after 1m0s
[19:39:05.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:27:0)
[19:39:05.666] Timer tick, asking Buildbucket for the build status
[19:39:05.872] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:05.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:28:0) after 1m0s
[19:40:05.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:28:0)
[19:40:05.936] Timer tick, asking Buildbucket for the build status
[19:40:06.063] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:06.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:29:0) after 1m0s
[19:41:06.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:29:0)
[19:41:06.052] Timer tick, asking Buildbucket for the build status
[19:41:06.281] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:06.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:30:0) after 1m0s
[19:42:06.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:30:0)
[19:42:06.376] Timer tick, asking Buildbucket for the build status
[19:42:06.556] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:06.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:31:0) after 1m0s
[19:43:06.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:31:0)
[19:43:06.655] Timer tick, asking Buildbucket for the build status
[19:43:07.473] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:07.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:32:0) after 1m0s
[19:44:07.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:32:0)
[19:44:07.568] Timer tick, asking Buildbucket for the build status
[19:44:07.722] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:07.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:33:0) after 1m0s
[19:45:07.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:33:0)
[19:45:07.794] Timer tick, asking Buildbucket for the build status
[19:45:08.027] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:08.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:34:0) after 1m0s
[19:46:08.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:34:0)
[19:46:08.087] Timer tick, asking Buildbucket for the build status
[19:46:08.212] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:08.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:35:0) after 1m0s
[19:47:08.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:35:0)
[19:47:08.244] Timer tick, asking Buildbucket for the build status
[19:47:08.367] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:08.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:36:0) after 1m0s
[19:48:08.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:36:0)
[19:48:08.393] Timer tick, asking Buildbucket for the build status
[19:48:08.486] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:08.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:37:0) after 1m0s
[19:49:08.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:37:0)
[19:49:08.524] Timer tick, asking Buildbucket for the build status
[19:49:08.646] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:08.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:38:0) after 1m0s
[19:50:08.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:38:0)
[19:50:08.693] Timer tick, asking Buildbucket for the build status
[19:50:09.210] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:09.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:39:0) after 1m0s
[19:51:09.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:39:0)
[19:51:09.318] Timer tick, asking Buildbucket for the build status
[19:51:09.457] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:09.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:40:0) after 1m0s
[19:52:09.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:40:0)
[19:52:09.475] Timer tick, asking Buildbucket for the build status
[19:52:09.668] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:09.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:41:0) after 1m0s
[19:53:09.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:41:0)
[19:53:09.747] Timer tick, asking Buildbucket for the build status
[19:53:10.264] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:10.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:42:0) after 1m0s
[19:54:10.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:42:0)
[19:54:10.241] Timer tick, asking Buildbucket for the build status
[19:54:11.018] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:11.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:43:0) after 1m0s
[19:55:11.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:43:0)
[19:55:11.053] Timer tick, asking Buildbucket for the build status
[19:55:11.186] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:11.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:44:0) after 1m0s
[19:56:11.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:44:0)
[19:56:11.236] Timer tick, asking Buildbucket for the build status
[19:56:11.516] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:11.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:45:0) after 1m0s
[19:57:11.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:45:0)
[19:57:11.540] Timer tick, asking Buildbucket for the build status
[19:57:11.844] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:11.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:46:0) after 1m0s
[19:58:11.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:46:0)
[19:58:11.919] Timer tick, asking Buildbucket for the build status
[19:58:11.989] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:11.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:47:0) after 1m0s
[19:59:12.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:47:0)
[19:59:12.152] Timer tick, asking Buildbucket for the build status
[19:59:12.265] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:12.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:48:0) after 1m0s
[20:00:12.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:48:0)
[20:00:12.311] Timer tick, asking Buildbucket for the build status
[20:00:12.528] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:12.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:49:0) after 1m0s
[20:01:12.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:49:0)
[20:01:12.658] Timer tick, asking Buildbucket for the build status
[20:01:12.806] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:12.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:50:0) after 1m0s
[20:02:12.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:50:0)
[20:02:12.922] Timer tick, asking Buildbucket for the build status
[20:02:13.103] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:13.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:51:0) after 1m0s
[20:03:13.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:51:0)
[20:03:13.117] Timer tick, asking Buildbucket for the build status
[20:03:13.486] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:13.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:52:0) after 1m0s
[20:04:13.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:52:0)
[20:04:13.535] Timer tick, asking Buildbucket for the build status
[20:04:13.866] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:13.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:53:0) after 1m0s
[20:05:14.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:53:0)
[20:05:14.014] Timer tick, asking Buildbucket for the build status
[20:05:14.151] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:14.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:54:0) after 1m0s
[20:06:14.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:54:0)
[20:06:14.165] Timer tick, asking Buildbucket for the build status
[20:06:14.294] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:14.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:55:0) after 1m0s
[20:07:14.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:55:0)
[20:07:14.436] Timer tick, asking Buildbucket for the build status
[20:07:14.556] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:14.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:56:0) after 1m0s
[20:08:14.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:56:0)
[20:08:14.603] Timer tick, asking Buildbucket for the build status
[20:08:14.877] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:14.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:57:0) after 1m0s
[20:09:14.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:57:0)
[20:09:14.911] Timer tick, asking Buildbucket for the build status
[20:09:15.048] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:15.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:58:0) after 1m0s
[20:10:15.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:58:0)
[20:10:15.066] Timer tick, asking Buildbucket for the build status
[20:10:15.210] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:15.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:59:0) after 1m0s
[20:11:15.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:59:0)
[20:11:15.255] Timer tick, asking Buildbucket for the build status
[20:11:15.388] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:15.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:60:0) after 1m0s
[20:12:15.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:60:0)
[20:12:15.416] Timer tick, asking Buildbucket for the build status
[20:12:15.809] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:15.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:61:0) after 1m0s
[20:13:15.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:61:0)
[20:13:15.831] Timer tick, asking Buildbucket for the build status
[20:13:15.968] Build 8857650081295729824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:15.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023080143427211344:62:0) after 1m0s
[20:13:23.257] Received PubSub notification, asking Buildbucket for the build status
[20:13:23.354] Build 8857650081295729824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:13:23.354] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611087201080910",
"created_by": "project:chromium",
"created_ts": "1611083635962109",
"id": "8857650081295729824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbbc85892facf232e750126a892056fbe640044e\"}}",
"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\": \"bbbc85892facf232e750126a892056fbe640044e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-nszv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16897, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"b69f54069cbf700a4646817805ca77c0d2a01292\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"bbbc85892facf232e750126a892056fbe640044e\", \"got_revision_cp\": \"refs/heads/master@{#844830}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"b28c3b8fed06f17e8d2dc3c265107d15ddba1185\", \"got_v8_revision_cp\": \"refs/heads/9.0.14@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"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\": \"bbbc85892facf232e750126a892056fbe640044e\", \"swarm_hashes_refs/heads/master(at){#844830}_without_patch\": {\"absl_hardening_tests\": \"eed92c43599232bfcc016a84ee88e86c796635e2\", \"accessibility_unittests\": \"4140b76452a23ccab1b2d2f1db81133bdf5ce90b\", \"angle_unittests\": \"0317f036400b1eff43e9979fcdaae51ff1afd605\", \"app_shell_unittests\": \"b8c598e246ffd5b35eb9618d00da33446dc2aa4f\", \"aura_unittests\": \"75e14fabfc87e92931c0cd61a0fd7ff7132b0e15\", \"base_unittests\": \"dbd0284736c9ea997a16091578695377ce97b158\", \"base_util_unittests\": \"f4c49e1e855bddccff41ac0446144093180a39d4\", \"blink_common_unittests\": \"f1a238ac469fd9135a074a5596c999231677aa00\", \"blink_fuzzer_unittests\": \"58bd4c191f36424fa0e9555f75ad95fcf742109c\", \"blink_heap_unittests\": \"fbf5f27981608979c9710b1fed0950b7cbb6ff13\", \"blink_platform_unittests\": \"5ea1bcb8b5f204ca90d169730c5bd403c1d66d6e\", \"blink_unittests\": \"fd9e00529aaa0d8e5d109413e075418405ed5c61\", \"boringssl_crypto_tests\": \"bc5d33a1097f049a099861a6c8638b7ee00292ac\", \"boringssl_ssl_tests\": \"76e657d731aae1dd5b0b0fa674d33bed298247f3\", \"browser_switcher_bho_unittests\": \"17acbe9a6c516e8d9b1a8c9fbd27a1fad8081bb4\", \"browser_tests\": \"68b06304392513baa37d967826ddcfa5d4804b22\", \"capture_unittests\": \"798cc346bcc4a51f468f0b2e00f6b30105496962\", \"cast_unittests\": \"cd88bab81f8ad971618b8917e0b93347a1f28be9\", \"cc_unittests\": \"6b158fe5f724c359c5ed66527c3ea6f40786c0e7\", \"chrome_app_unittests\": \"7b64b27862b4007d6d61cf34a985247ec6924757\", \"chrome_cleaner_unittests\": \"278e85a98362e8eaca0c445e461412601759eb86\", \"chrome_elf_unittests\": \"eb6799d774467723fb0ff444eac7d593a8ec9b71\", \"chromedriver_unittests\": \"e5bb7444cf6bac67e1081b4d3508bfa0433ad9e1\", \"components_browsertests\": \"4efd08bcf51a714335b9861989fc013d6454be78\", \"components_unittests\": \"2fa670d9169497f6bcdfe8301cce48c5072d9724\", \"compositor_unittests\": \"1135b0ce5f7de234c14a9a38307236e3fe88dbd1\", \"content_browsertests\": \"1e8030fbd092a110d130e34f20d8b8e6f965f4e7\", \"content_unittests\": \"d4a263b8bca6a2b715afa3c00f0f31532bc07193\", \"courgette_unittests\": \"61812110f8bdd381d2e6cdd39d9e95988e43b1eb\", \"crashpad_tests\": \"45c4b8372a1ae05dac5e596d9ec1775f20bb2a4b\", \"cronet_tests\": \"4b026f9909e8dadcc4aa27493cb8754f6a64959f\", \"cronet_unittests\": \"90b9b721bb6399c5ec13e40a8545d597618c0106\", \"crypto_unittests\": \"aa3b06ae2d606a3f49f4889a04b891592c862702\", \"delayloads_unittests\": \"78d9cd274063b131919384637290948877a150db\", \"device_unittests\": \"6ec84d851f5c500e42686da35c8fe344f4aa4254\", \"display_unittests\": \"33d370fef6c9ca69796208a9787f00bbee1002cc\", \"elevation_service_unittests\": \"1763f6940d5837b534b249d532dba83c63847b4b\", \"events_unittests\": \"ca5eeebec609523db46467c599d7dbd75d8a76e4\", \"extensions_browsertests\": \"5d4cf8f536cdddfb30a16a323a0aa804ff48dabd\", \"extensions_unittests\": \"11a6c15920c725e28406c0c953d768cae62c7976\", \"filesystem_service_unittests\": \"acd201ed4a5ca85553f3bfa897a01bebf825f616\", \"gcm_unit_tests\": \"b734a482746c148704b95c686bab0ffdb2f59637\", \"gcp_unittests\": \"4910c17b003d33987a4ea89901d645053cc8eaa8\", \"gfx_unittests\": \"1de5e91da1fb5f9b3d3af5f2c3f3892ab605dcdd\", \"gin_unittests\": \"cfe6f17453d44b771563dad1935c78927a03a625\", \"google_apis_unittests\": \"54fa765cee88eb11cf25c4b4c563e49717dc916e\", \"gpu_unittests\": \"728732d80275924aa4801f8254f84694039db181\", \"gwp_asan_unittests\": \"f7b5d5e9a9eac295650e70b1d051a789363d7eb2\", \"headless_browsertests\": \"d82818b065e08d7e0c720c52e0737bb823330b5f\", \"headless_unittests\": \"367cd2d3658f15e0ea0a3c1b2043d0e4c1f92bc8\", \"install_static_unittests\": \"77f6cbde26dec86b4a5879511695b31d91a45a9c\", \"installer_util_unittests\": \"a1765d56714093004999ee25e974d6bf08729c87\", \"interactive_ui_tests\": \"e6ef31a604994fdc4e61def02ebb7a04bc8f43cd\", \"ipc_tests\": \"7ed51d370ae5b029f0b997c1bf0ac4eb54fd8efc\", \"jingle_unittests\": \"7db17b7f44c042693e5142d0efb057b68f597b84\", \"latency_unittests\": \"e358f275a56f378f31a425d28ebfbf6bfb3d3c7a\", \"libjingle_xmpp_unittests\": \"e2813ad8c783567d1aef5f0a532898ee75c892a0\", \"liburlpattern_unittests\": \"5a043d23caac014165d77fb1b2a670401f7c9235\", \"media_blink_unittests\": \"0a9e1349e71295d28f25d1b0db4812e9ba1ca50f\", \"media_unittests\": \"4e3b9d6af639459b910a54f7e3f80eb30dc5c743\", \"message_center_unittests\": \"9d75c81bc63cbb75c3b11716a54bbfcdcbb328cf\", \"midi_unittests\": \"9ecd52143e83bc47032e74f1ab895ff5e85fa0cc\", \"mojo_core_unittests\": \"6207afcc95e06cb9fc8a5f5fc7e1b8c982f2ad56\", \"mojo_unittests\": \"583c8861230053c3db7a22680a474ff1454920ba\", \"nacl_loader_unittests\": \"0e60ba0c943080357a08e08621c0951d5ca85e83\", \"native_theme_unittests\": \"8b07efe254f8c059fea5bf8c55ddb8ede1e27bab\", \"net_unittests\": \"7883d4a37ca9fd516a59c66f0c9759cc234482b8\", \"notification_helper_unittests\": \"23a454ec3b02a2fb9f8582d5fb8389273b4f3c01\", \"pdf_unittests\": \"1921d139b112ae955157e8fc555b61f14b199537\", \"perfetto_unittests\": \"c6bc5fc512a250e5702f41aa646de6245423cdb2\", \"ppapi_unittests\": \"280a18b5a088602be531d3be942532e0d70019ce\", \"printing_unittests\": \"0d6042b420d137b47fcdde85ff226833b570d5a0\", \"remoting_unittests\": \"5c2287f263fce8260ff6bdf1ec0c7cb9fa91d3e7\", \"sbox_integration_tests\": \"5cfc35d74db2efe9fd5ffaa5e2a34859df8678f8\", \"sbox_unittests\": \"0b028ceea80433cf3d72b8e12b9d2d9bdec3d989\", \"sbox_validation_tests\": \"3cea6d9eeaeab3e18809f7e505882ce4ad06ce94\", \"service_manager_unittests\": \"e552f0ffcea05149cc69b995668dd22a97035eb6\", \"services_unittests\": \"0aaadb0b2e7ba34469ec4920eaa5badfc9f67a28\", \"setup_unittests\": \"0d48ab6dc093e6c9481c13269bb9e812f4a30367\", \"shell_dialogs_unittests\": \"f87671ba00c3b23915f8e9fe4acc280dfcb339fe\", \"skia_unittests\": \"c7a2046fdea1d45ff6c0129d284d9d166e0e764e\", \"snapshot_unittests\": \"05ff85cee9808fc77233ba8954da97b885f7fd55\", \"sql_unittests\": \"997444a0d81c8ca000eee269aab572dae968b353\", \"storage_unittests\": \"d167f625418b124d8f8de329aed2dc37262d0934\", \"sync_integration_tests\": \"333efd858d64abd130b04e3cd9f73aa661a77afe\", \"traffic_annotation_auditor_unittests\": \"d0976d0f1928a8088d56a792bb0c5daf430413a5\", \"ui_base_unittests\": \"3ff401c2459bfc7a13df228a10949870a494a3b4\", \"ui_touch_selection_unittests\": \"4bbf37b431621a728892e9a9e980f0dd6d21b454\", \"unit_tests\": \"c6b6010fbaad0b79965aaacc2ebd22aa40dca184\", \"updater_tests\": \"f21a6a0753dab6db88c836d010f54b23096e071f\", \"url_unittests\": \"5ff5e30eddb2bc440fa3da76374383a4aa1d3c11\", \"views_unittests\": \"92e3ef55de372895129022d872fef88b027829f4\", \"viz_unittests\": \"e18d72db62fea655dfd214c63a563fe915fe7542\", \"vr_common_unittests\": \"ead87be237d70c6758f37a421fa457c5ae4118f0\", \"vr_pixeltests\": \"e38b24fc3d8bab37b25e670b1bd31413be5d3384\", \"weblayer_browsertests\": \"58891f74a795a0d7f3781c843b5abddf79907216\", \"weblayer_unittests\": \"c0833c7e8229489226056839f54e2be0c259481e\", \"wm_unittests\": \"02ce1659eddc6715d5194bbe685a838a3000b910\", \"wtf_unittests\": \"13c891e36f3c11ad8b7ad427986544ea66101470\", \"zlib_unittests\": \"a611ea2b1158e5401e63e83cd03366c322966ebd\", \"zucchini_unittests\": \"035aa851c72e8a8fae12d88bcb0e760be63d45cc\"}}, \"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-0-nszv\"], \"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\": [\"5666-679c9bd\"], \"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": "1611083637458332",
"status": "COMPLETED",
"status_changed_ts": "1611087201824071",
"tags": [
"build_address:luci.chromium.ci/win-asan/16897",
"builder:win-asan",
"buildset:commit/git/bbbc85892facf232e750126a892056fbe640044e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbbc85892facf232e750126a892056fbe640044e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023080143427211344",
"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/8857650081295729824/+/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:5134e2e8fa940b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611087201889579",
"url": "https://ci.chromium.org/b/8857650081295729824",
"utcnow_ts": "1611087203344134"
}
}
[20:13:23.354] Invocation finished in 59m28.808700748s with status SUCCEEDED