[20:22:48.092] New invocation is queued and will start shortly
[20:22:49.199] Starting the invocation (attempt 1)
[20:22:49.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:22:49.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:22:49.248] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020357900113696608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0fd80c477c584f104b490dc2f863106cad9d11f9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020357900113696608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0fd80c477c584f104b490dc2f863106cad9d11f9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fd80c477c584f104b490dc2f863106cad9d11f9",
"gitiles_ref:refs/heads/master"
]
}
[20:22:50.043] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613679769348474",
"id": "8854927838134683904",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0fd80c477c584f104b490dc2f863106cad9d11f9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613679769792358",
"tags": [
"build_address:luci.chromium.ci/win-asan/17414",
"builder:win-asan",
"buildset:commit/git/0fd80c477c584f104b490dc2f863106cad9d11f9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fd80c477c584f104b490dc2f863106cad9d11f9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020357900113696608",
"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/8854927838134683904/+/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": "1613679769792521",
"url": "https://ci.chromium.org/b/8854927838134683904",
"utcnow_ts": "1613679770036267"
}
}
[20:22:50.043] Task URL: https://ci.chromium.org/b/8854927838134683904
[20:22:50.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:2:0) after 1m0s
[20:22:51.752] Received PubSub notification, asking Buildbucket for the build status
[20:22:52.097] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:50.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:2:0)
[20:23:50.106] Timer tick, asking Buildbucket for the build status
[20:23:50.185] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:50.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:4:0) after 1m0s
[20:24:50.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:4:0)
[20:24:50.216] Timer tick, asking Buildbucket for the build status
[20:24:50.336] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:50.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:5:0) after 1m0s
[20:25:50.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:5:0)
[20:25:50.313] Timer tick, asking Buildbucket for the build status
[20:25:50.377] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:6:0) after 1m0s
[20:26:50.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:6:0)
[20:26:50.415] Timer tick, asking Buildbucket for the build status
[20:26:50.542] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:50.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:7:0) after 1m0s
[20:27:50.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:7:0)
[20:27:50.613] Timer tick, asking Buildbucket for the build status
[20:27:50.681] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:50.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:8:0) after 1m0s
[20:28:50.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:8:0)
[20:28:50.675] Timer tick, asking Buildbucket for the build status
[20:28:50.944] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:50.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:9:0) after 1m0s
[20:29:51.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:9:0)
[20:29:51.011] Timer tick, asking Buildbucket for the build status
[20:29:51.086] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:51.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:10:0) after 1m0s
[20:30:51.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:10:0)
[20:30:51.115] Timer tick, asking Buildbucket for the build status
[20:30:51.232] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:51.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:11:0) after 1m0s
[20:31:51.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:11:0)
[20:31:51.251] Timer tick, asking Buildbucket for the build status
[20:31:51.335] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:51.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:12:0) after 1m0s
[20:32:51.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:12:0)
[20:32:51.335] Timer tick, asking Buildbucket for the build status
[20:32:51.461] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:51.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:13:0) after 1m0s
[20:33:51.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:13:0)
[20:33:51.522] Timer tick, asking Buildbucket for the build status
[20:33:51.582] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:51.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:14:0) after 1m0s
[20:34:51.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:14:0)
[20:34:51.656] Timer tick, asking Buildbucket for the build status
[20:34:51.731] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:51.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:15:0) after 1m0s
[20:35:51.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:15:0)
[20:35:51.728] Timer tick, asking Buildbucket for the build status
[20:35:51.808] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:51.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:16:0) after 1m0s
[20:36:51.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:16:0)
[20:36:51.848] Timer tick, asking Buildbucket for the build status
[20:36:51.918] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:51.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:17:0) after 1m0s
[20:37:52.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:17:0)
[20:37:52.066] Timer tick, asking Buildbucket for the build status
[20:37:52.162] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:52.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:18:0) after 1m0s
[20:38:52.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:18:0)
[20:38:52.195] Timer tick, asking Buildbucket for the build status
[20:38:52.281] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:52.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:19:0) after 1m0s
[20:39:52.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:19:0)
[20:39:52.331] Timer tick, asking Buildbucket for the build status
[20:39:52.414] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:52.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:20:0) after 1m0s
[20:40:52.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:20:0)
[20:40:52.505] Timer tick, asking Buildbucket for the build status
[20:40:52.642] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:52.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:21:0) after 1m0s
[20:41:52.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:21:0)
[20:41:52.678] Timer tick, asking Buildbucket for the build status
[20:41:52.871] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:52.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:22:0) after 1m0s
[20:42:52.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:22:0)
[20:42:52.968] Timer tick, asking Buildbucket for the build status
[20:42:53.378] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:53.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:23:0) after 1m0s
[20:43:53.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:23:0)
[20:43:53.397] Timer tick, asking Buildbucket for the build status
[20:43:53.853] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:53.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:24:0) after 1m0s
[20:44:53.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:24:0)
[20:44:53.915] Timer tick, asking Buildbucket for the build status
[20:44:54.012] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:54.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:25:0) after 1m0s
[20:45:54.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:25:0)
[20:45:54.037] Timer tick, asking Buildbucket for the build status
[20:45:54.138] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:54.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:26:0) after 1m0s
[20:46:54.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:26:0)
[20:46:54.161] Timer tick, asking Buildbucket for the build status
[20:46:54.281] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:54.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:27:0) after 1m0s
[20:47:54.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:27:0)
[20:47:54.330] Timer tick, asking Buildbucket for the build status
[20:47:54.498] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:28:0) after 1m0s
[20:48:54.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:28:0)
[20:48:54.545] Timer tick, asking Buildbucket for the build status
[20:48:54.619] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:54.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:29:0) after 1m0s
[20:49:54.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:29:0)
[20:49:54.682] Timer tick, asking Buildbucket for the build status
[20:49:55.081] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:55.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:30:0) after 1m0s
[20:50:55.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:30:0)
[20:50:55.121] Timer tick, asking Buildbucket for the build status
[20:50:55.208] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:55.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:31:0) after 1m0s
[20:51:55.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:31:0)
[20:51:55.287] Timer tick, asking Buildbucket for the build status
[20:51:55.379] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:55.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:32:0) after 1m0s
[20:52:55.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:32:0)
[20:52:55.427] Timer tick, asking Buildbucket for the build status
[20:52:55.515] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:55.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:33:0) after 1m0s
[20:53:55.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:33:0)
[20:53:55.539] Timer tick, asking Buildbucket for the build status
[20:53:55.877] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:55.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:34:0) after 1m0s
[20:54:55.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:34:0)
[20:54:55.900] Timer tick, asking Buildbucket for the build status
[20:54:55.986] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:55.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:35:0) after 1m0s
[20:55:56.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:35:0)
[20:55:56.057] Timer tick, asking Buildbucket for the build status
[20:55:56.323] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:56.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:36:0) after 1m0s
[20:56:56.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:36:0)
[20:56:56.372] Timer tick, asking Buildbucket for the build status
[20:56:56.457] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:37:0) after 1m0s
[20:57:56.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:37:0)
[20:57:56.486] Timer tick, asking Buildbucket for the build status
[20:57:57.182] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:57.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:38:0) after 1m0s
[20:58:57.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:38:0)
[20:58:57.268] Timer tick, asking Buildbucket for the build status
[20:58:57.412] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:57.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:39:0) after 1m0s
[20:59:57.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:39:0)
[20:59:57.429] Timer tick, asking Buildbucket for the build status
[20:59:57.524] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:57.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:40:0) after 1m0s
[21:00:57.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:40:0)
[21:00:57.583] Timer tick, asking Buildbucket for the build status
[21:00:57.766] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:57.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:41:0) after 1m0s
[21:01:57.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:41:0)
[21:01:57.796] Timer tick, asking Buildbucket for the build status
[21:01:57.879] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:57.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:42:0) after 1m0s
[21:02:57.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:42:0)
[21:02:57.964] Timer tick, asking Buildbucket for the build status
[21:02:58.124] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:58.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:43:0) after 1m0s
[21:03:58.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:43:0)
[21:03:58.244] Timer tick, asking Buildbucket for the build status
[21:03:58.675] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:58.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:44:0) after 1m0s
[21:04:58.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:44:0)
[21:04:58.704] Timer tick, asking Buildbucket for the build status
[21:04:58.805] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:58.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:45:0) after 1m0s
[21:05:58.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:45:0)
[21:05:58.817] Timer tick, asking Buildbucket for the build status
[21:05:58.909] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:58.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:46:0) after 1m0s
[21:06:58.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:46:0)
[21:06:58.959] Timer tick, asking Buildbucket for the build status
[21:06:59.075] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:59.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:47:0) after 1m0s
[21:07:59.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:47:0)
[21:07:59.110] Timer tick, asking Buildbucket for the build status
[21:07:59.286] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:59.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:48:0) after 1m0s
[21:08:59.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:48:0)
[21:08:59.350] Timer tick, asking Buildbucket for the build status
[21:08:59.430] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:59.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:49:0) after 1m0s
[21:09:59.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:49:0)
[21:09:59.449] Timer tick, asking Buildbucket for the build status
[21:09:59.668] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:59.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:50:0) after 1m0s
[21:10:59.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:50:0)
[21:10:59.706] Timer tick, asking Buildbucket for the build status
[21:10:59.882] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:59.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:51:0) after 1m0s
[21:12:00.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:51:0)
[21:12:00.028] Timer tick, asking Buildbucket for the build status
[21:12:00.164] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:00.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:52:0) after 1m0s
[21:13:00.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:52:0)
[21:13:00.217] Timer tick, asking Buildbucket for the build status
[21:13:00.310] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:00.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:53:0) after 1m0s
[21:14:01.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:53:0)
[21:14:01.278] Timer tick, asking Buildbucket for the build status
[21:14:01.646] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:01.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:54:0) after 1m0s
[21:15:01.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:54:0)
[21:15:01.742] Timer tick, asking Buildbucket for the build status
[21:15:01.935] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:01.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:55:0) after 1m0s
[21:16:02.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:55:0)
[21:16:02.015] Timer tick, asking Buildbucket for the build status
[21:16:02.499] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:02.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:56:0) after 1m0s
[21:17:02.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:56:0)
[21:17:02.552] Timer tick, asking Buildbucket for the build status
[21:17:02.651] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:57:0) after 1m0s
[21:18:02.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:57:0)
[21:18:02.782] Timer tick, asking Buildbucket for the build status
[21:18:03.084] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:03.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:58:0) after 1m0s
[21:19:03.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:58:0)
[21:19:03.167] Timer tick, asking Buildbucket for the build status
[21:19:14.530] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:14.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:59:0) after 1m0s
[21:20:14.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:59:0)
[21:20:14.554] Timer tick, asking Buildbucket for the build status
[21:20:14.928] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:14.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:60:0) after 1m0s
[21:21:15.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:60:0)
[21:21:15.001] Timer tick, asking Buildbucket for the build status
[21:21:15.329] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:15.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:61:0) after 1m0s
[21:22:15.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:61:0)
[21:22:15.385] Timer tick, asking Buildbucket for the build status
[21:22:15.471] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:62:0) after 1m0s
[21:23:15.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:62:0)
[21:23:15.483] Timer tick, asking Buildbucket for the build status
[21:23:15.594] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:15.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:63:0) after 1m0s
[21:24:15.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:63:0)
[21:24:15.644] Timer tick, asking Buildbucket for the build status
[21:24:15.725] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:15.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:64:0) after 1m0s
[21:25:15.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:64:0)
[21:25:15.771] Timer tick, asking Buildbucket for the build status
[21:25:15.882] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:15.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:65:0) after 1m0s
[21:26:15.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:65:0)
[21:26:15.912] Timer tick, asking Buildbucket for the build status
[21:26:15.997] Build 8854927838134683904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:15.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020357900113696608:66:0) after 1m0s
[21:26:46.735] Received PubSub notification, asking Buildbucket for the build status
[21:26:47.749] Build 8854927838134683904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:26:47.749] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613683605348505",
"created_by": "project:chromium",
"created_ts": "1613679769348474",
"id": "8854927838134683904",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0fd80c477c584f104b490dc2f863106cad9d11f9\"}}",
"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\": \"0fd80c477c584f104b490dc2f863106cad9d11f9\", \"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-1-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17414, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"94607724a7afa8da2973346d0f5edfa12afbc2a8\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"0fd80c477c584f104b490dc2f863106cad9d11f9\", \"got_revision_cp\": \"refs/heads/master@{#855398}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"dc5bb2195a9ab47c0ffab51364c2e18cee90a18c\", \"got_v8_revision\": \"d72f5fbbf564ab7b1cec36925f02d1978d1c427d\", \"got_v8_revision_cp\": \"refs/heads/9.0.215@{#1}\", \"got_webrtc_revision\": \"17f914ce50ece85310aa30b5ae4ec3623ec0ce3a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33294}\", \"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\": \"0fd80c477c584f104b490dc2f863106cad9d11f9\", \"swarm_hashes_refs/heads/master(at){#855398}_without_patch\": {\"absl_hardening_tests\": \"d6f364d6826162d9115a702c59bcbc0138430dae\", \"accessibility_unittests\": \"48fa2e3890def65f3c8614b373dedc6ea72f32fe\", \"angle_unittests\": \"e2014f35c0fdf8730381403ae67bf96a329de4f8\", \"app_shell_unittests\": \"02222f420a754eeec6fb5b9bdfe47dd2d9d3b123\", \"aura_unittests\": \"1241adbc9775fdc5becafcf6a165a41d086804c6\", \"base_unittests\": \"8f8aa68bfca3472481fa77483e348aee41392ca5\", \"base_util_unittests\": \"00d3460376068eed2ef7cad6b3fa5f9c40740b6a\", \"blink_common_unittests\": \"ed9a9462b8236569ce1b6f6cf970a075bb0969d3\", \"blink_fuzzer_unittests\": \"4ddae512438a534ca8648ad9f2137e834cad1062\", \"blink_heap_unittests\": \"2e93dca00991c03ba7db80158f5684c3a3c19029\", \"blink_platform_unittests\": \"bbaa6052bbd21f49513a4486c96e9514693e4102\", \"blink_unittests\": \"4537cb0a38df1ad8a566a510b9a84b989a94e5e9\", \"boringssl_crypto_tests\": \"9ff613160460f57aa873a1d166a4f737de6eac28\", \"boringssl_ssl_tests\": \"af827da2f73f9e2b13050b11dbe3124b53eb9ac5\", \"browser_switcher_bho_unittests\": \"f78e3e58787643a5a5b1286acf4cfa1e3636e63c\", \"browser_tests\": \"13f7f935da3be85566e4cf286e1708a783cc1787\", \"capture_unittests\": \"438c62f4e2544b888a224b2746f47c1cb43d58d8\", \"cast_unittests\": \"5984aab685fbd389122989a03c567b238a7839ae\", \"cc_unittests\": \"3f86aed39676137ce52c9542e665f8ef039be812\", \"chrome_app_unittests\": \"2794f3df46672b8ec9b1744145fb3943108e981a\", \"chrome_cleaner_unittests\": \"d21d0574647b56ac5427b6c43225f6e5316acc7d\", \"chrome_elf_unittests\": \"05c01b53c428792ea92a494c9aec60f8806f2cd7\", \"chromedriver_unittests\": \"684f9decfff91d7ace7aa5a0e259c22646ebab48\", \"components_browsertests\": \"1250c136575940bcf0e07b5cbcba07a64e3ac8b1\", \"components_unittests\": \"b59c6f35d4316f4bd4525443de8a105183432b33\", \"compositor_unittests\": \"d27615e4dac0b1a5cfc95647935e651d7605651f\", \"content_browsertests\": \"b0bcd44f471a9ee1d3e5801270ffef0fef7fe66e\", \"content_unittests\": \"ff9ac7965f47f10644d41d6004f8b6c588fd1adf\", \"courgette_unittests\": \"1ff34a8e374213fe30b8951bfda45db80641259a\", \"crashpad_tests\": \"d0aeaa8a201dd2d114ca66bd83f4a0e0daad9e92\", \"cronet_tests\": \"25bee06a7abc49b6eaf2702f20a9f93e2bbfa6b7\", \"cronet_unittests\": \"be7eac0687bbe82678a987bee45c609c1e38ee7a\", \"crypto_unittests\": \"f2d25b0b7f0401bde9c2f086cd3fa16d14e76c70\", \"delayloads_unittests\": \"a6ccab602a39db8db3bf6e8539e990b2c0552787\", \"device_unittests\": \"3d889639bafb222c8c069456ef75ba84fec28fa6\", \"display_unittests\": \"6d699c9eefcac7587ae5bdfbefa5f5a8ffbd895f\", \"elevation_service_unittests\": \"fdb9e403470bfc4f9959f4414dc3a77e9ed9fc8a\", \"events_unittests\": \"90f60ff2544553c15797db82d213fe6e44ba3bdd\", \"extensions_browsertests\": \"96b2e1cfb9ff591a228fb5c3b0ab351cbcf640fd\", \"extensions_unittests\": \"c5097842da9dfc91fac7105dad1973f25adc7d57\", \"filesystem_service_unittests\": \"2c104ef826575d06839584806a51fc75a996ef7b\", \"gcm_unit_tests\": \"3efbf096e9811d13c7d78aa60918a38dd20045ca\", \"gcp_unittests\": \"5408b809d04e88a71979eac982f57895d2b23961\", \"gfx_unittests\": \"1b9d504334708daf2d0909be4e9b835140028f4b\", \"gin_unittests\": \"c39737992d339a83209a9b952eb165ce4ae17a44\", \"google_apis_unittests\": \"6b670d7c94e16b9936ea7dcabb1f89fbfa2f1097\", \"gpu_unittests\": \"f047c6903811351b6823f19fe618430d08446eed\", \"gwp_asan_unittests\": \"87fb407461e531b1d1f933b3088e60a4b606672f\", \"headless_browsertests\": \"15e9041f0b981d26edd68738a88a64c449655c9b\", \"headless_unittests\": \"f009c3810f1e937549b4f79a86950216ca32a8ad\", \"install_static_unittests\": \"940861d0f56f6c9afc87108df798c8ca65111981\", \"installer_util_unittests\": \"df91555aa2aecb54fca67a203f15f7c50bec989e\", \"interactive_ui_tests\": \"8ce7a50ba6798d5e177152e3fb74fe7cedd3ccd0\", \"ipc_tests\": \"90789c60f95af74e555d451f74e0ace7d394c9ae\", \"jingle_unittests\": \"0dd7a1326d0d805ba0c19b974a214f128ca7b960\", \"latency_unittests\": \"64dcc0565492bc3ea2301c3f392caf659710609f\", \"libjingle_xmpp_unittests\": \"1bc55e624ead4c5a6668a0b9f4c61a0ee6fc3d75\", \"liburlpattern_unittests\": \"61e0ae013d24f14edccd0154635f3b9ae49132f0\", \"media_blink_unittests\": \"6bbd59f6e9a88c2f75e8df7a2d1a1c117caa8fb7\", \"media_unittests\": \"a909e16d272ca19f22d0e6d1defc2e1fcc1157f0\", \"message_center_unittests\": \"5e7e46932138f935a2e4f136be3d1a85b3f3fde5\", \"midi_unittests\": \"6b68b57bd5063ce15f628ac2835d9f38bea382ef\", \"mojo_core_unittests\": \"9503246f1a8f7f8b3abb2d550ea487fe4de4bcde\", \"mojo_unittests\": \"c072401163c7aa9575eebc99d6944678d907d7f5\", \"nacl_loader_unittests\": \"faaf74cba6e4b753d0ac9f284f03382a90b25bf0\", \"native_theme_unittests\": \"56168092a01e4ccc479e4659e25e1d01306837a3\", \"net_unittests\": \"246488622b60ce6f9ba155fcbe4c21530af1046d\", \"notification_helper_unittests\": \"eed624333b75fbab08528f6390a10c15ab630dcf\", \"pdf_unittests\": \"1f30ddb6962981763d420187c86f7400fcd03719\", \"perfetto_unittests\": \"e0f59c51bb827708a09790762b2f90f724917728\", \"ppapi_unittests\": \"a505a31643344360997362eef24ad188e8ad9d2e\", \"printing_unittests\": \"d3a611dd3633e0d7a87b6505340df06db2ef5664\", \"remoting_unittests\": \"1ed5b40b7f89b347e33961f036c6968514280d92\", \"sbox_integration_tests\": \"7a6139b6fefa966e946f68d63a7ad123e0085054\", \"sbox_unittests\": \"7cb3a843f0f8d53c5a8dedb541e00f9a1d94e34f\", \"sbox_validation_tests\": \"dd9e4854d7dde2e44d34eb0a7db0373e93358f54\", \"service_manager_unittests\": \"4e6c3a15c690f1bfe141f28d8caeedef74076f5c\", \"services_unittests\": \"592a01c80729c1792569822c278c52b044f5bd77\", \"setup_unittests\": \"7a482f53e1f4fd87dd2c0c33860314ab6095b73f\", \"shell_dialogs_unittests\": \"332b0d8f0131a57a547a289e99712258f355daf6\", \"skia_unittests\": \"ad9a8c16308edd8a08df9fdc3d4e7a5e5cf8fa2c\", \"snapshot_unittests\": \"15686a3ed6fdec169b5552ed21ecf3d15c7e2675\", \"sql_unittests\": \"6eb19d67f699b308c68d1e6e3f1beaffaba89e0d\", \"storage_unittests\": \"b94a1245e288041cd6c973f4f17efcc40a249235\", \"sync_integration_tests\": \"6300e709f5fff749f5467a6baa1b339fbcc853b1\", \"traffic_annotation_auditor_unittests\": \"00ffea3c2ac97247d28c3c19ab4e1ca88b3bc498\", \"ui_base_unittests\": \"7b3159dbbc0fe243c939466348a2a73b978a4145\", \"ui_touch_selection_unittests\": \"54c2fd20b0bfedc5e9a47730c30fddd7873a0f1f\", \"unit_tests\": \"33182e242d2873e7c96d9bfbca56a2afe60b8b06\", \"updater_tests\": \"b6db939a4c0bf3816ddae85177f688dbd103768c\", \"url_unittests\": \"2084a609522081dc1aa7eb6c62f0bfae5bcef66e\", \"views_unittests\": \"801a429dc1d6d86ae680af9982aa0a08313c4c44\", \"viz_unittests\": \"c1ecf848faa2bb74d957d8321e1390c24a785f69\", \"vr_common_unittests\": \"66fcff691ff823222afd42ff1b6516fe6d137491\", \"vr_pixeltests\": \"20e8e7becf5924316d461d8509ee37daaa9fe413\", \"weblayer_browsertests\": \"d504c3504d75f01489cd25d8bfa8ef203d23a419\", \"weblayer_unittests\": \"21e6aa53f1e245e5fb8c91de46b8fbc5c768d852\", \"wm_unittests\": \"a8b8859d73a106c4bd716b70a3621a50b843d887\", \"wtf_unittests\": \"ba1bc7922d5ba5b894c37cb34a6539970076a0de\", \"zlib_unittests\": \"e5d605ce5a34effe5d726b40ace3447fde3ba099\", \"zucchini_unittests\": \"c7dcf2bcb6607dfd7d02113863fff308355a2a3c\"}}, \"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-1-2dz0\"], \"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\": [\"5700-b36f18b\"], \"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": "1613679770954663",
"status": "COMPLETED",
"status_changed_ts": "1613683605953186",
"tags": [
"build_address:luci.chromium.ci/win-asan/17414",
"builder:win-asan",
"buildset:commit/git/0fd80c477c584f104b490dc2f863106cad9d11f9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fd80c477c584f104b490dc2f863106cad9d11f9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020357900113696608",
"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/8854927838134683904/+/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:51cfa0c2841dc110",
"user_agent:luci-scheduler"
],
"updated_ts": "1613683605957307",
"url": "https://ci.chromium.org/b/8854927838134683904",
"utcnow_ts": "1613683607696644"
}
}
[21:26:47.749] Invocation finished in 1h3m59.672772012s with status SUCCEEDED