[04:29:48.077] New invocation is queued and will start shortly
[04:29:49.374] Starting the invocation (attempt 1)
[04:29:49.408] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:29:49.408] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:29:49.409] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026487854454810480",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a5dce97eab60586fc2b1c810dcd98892b0078329\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026487854454810480",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a5dce97eab60586fc2b1c810dcd98892b0078329",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5dce97eab60586fc2b1c810dcd98892b0078329",
"gitiles_ref:refs/heads/master"
]
}
[04:29:50.219] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607833789524576",
"id": "8861057792274338080",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a5dce97eab60586fc2b1c810dcd98892b0078329\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607833789989620",
"tags": [
"build_address:luci.chromium.ci/win-asan/16191",
"builder:win-asan",
"buildset:commit/git/a5dce97eab60586fc2b1c810dcd98892b0078329",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5dce97eab60586fc2b1c810dcd98892b0078329",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026487854454810480",
"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/8861057792274338080/+/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": "1607833789989787",
"url": "https://ci.chromium.org/b/8861057792274338080",
"utcnow_ts": "1607833790210740"
}
}
[04:29:50.219] Task URL: https://ci.chromium.org/b/8861057792274338080
[04:29:50.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:2:0) after 1m0s
[04:29:52.936] Received PubSub notification, asking Buildbucket for the build status
[04:29:53.045] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:50.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:2:0)
[04:30:50.272] Timer tick, asking Buildbucket for the build status
[04:30:50.409] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:50.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:4:0) after 1m0s
[04:31:50.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:4:0)
[04:31:50.436] Timer tick, asking Buildbucket for the build status
[04:31:50.538] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:50.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:5:0) after 1m0s
[04:32:50.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:5:0)
[04:32:50.553] Timer tick, asking Buildbucket for the build status
[04:32:50.658] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:6:0) after 1m0s
[04:33:50.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:6:0)
[04:33:50.678] Timer tick, asking Buildbucket for the build status
[04:33:51.012] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:51.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:7:0) after 1m0s
[04:34:51.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:7:0)
[04:34:51.031] Timer tick, asking Buildbucket for the build status
[04:34:51.126] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:51.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:8:0) after 1m0s
[04:35:51.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:8:0)
[04:35:51.145] Timer tick, asking Buildbucket for the build status
[04:35:51.347] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:51.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:9:0) after 1m0s
[04:36:51.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:9:0)
[04:36:51.378] Timer tick, asking Buildbucket for the build status
[04:36:51.621] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:51.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:10:0) after 1m0s
[04:37:51.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:10:0)
[04:37:51.628] Timer tick, asking Buildbucket for the build status
[04:37:51.686] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:51.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:11:0) after 1m0s
[04:38:51.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:11:0)
[04:38:51.705] Timer tick, asking Buildbucket for the build status
[04:38:51.767] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:12:0) after 1m0s
[04:39:51.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:12:0)
[04:39:51.816] Timer tick, asking Buildbucket for the build status
[04:39:52.224] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:52.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:13:0) after 1m0s
[04:40:52.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:13:0)
[04:40:52.243] Timer tick, asking Buildbucket for the build status
[04:40:52.373] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:52.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:14:0) after 1m0s
[04:41:52.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:14:0)
[04:41:52.401] Timer tick, asking Buildbucket for the build status
[04:41:52.814] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:52.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:15:0) after 1m0s
[04:42:52.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:15:0)
[04:42:52.874] Timer tick, asking Buildbucket for the build status
[04:42:53.100] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:53.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:16:0) after 1m0s
[04:43:53.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:16:0)
[04:43:53.118] Timer tick, asking Buildbucket for the build status
[04:43:53.294] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:53.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:17:0) after 1m0s
[04:44:53.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:17:0)
[04:44:53.351] Timer tick, asking Buildbucket for the build status
[04:44:53.503] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:53.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:18:0) after 1m0s
[04:45:53.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:18:0)
[04:45:53.519] Timer tick, asking Buildbucket for the build status
[04:45:53.622] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:53.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:19:0) after 1m0s
[04:46:53.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:19:0)
[04:46:53.642] Timer tick, asking Buildbucket for the build status
[04:46:54.072] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:54.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:20:0) after 1m0s
[04:47:54.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:20:0)
[04:47:54.124] Timer tick, asking Buildbucket for the build status
[04:47:54.254] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:54.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:21:0) after 1m0s
[04:48:54.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:21:0)
[04:48:54.270] Timer tick, asking Buildbucket for the build status
[04:48:54.657] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:54.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:22:0) after 1m0s
[04:49:54.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:22:0)
[04:49:54.707] Timer tick, asking Buildbucket for the build status
[04:49:54.816] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:54.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:23:0) after 1m0s
[04:50:54.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:23:0)
[04:50:54.869] Timer tick, asking Buildbucket for the build status
[04:50:54.988] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:54.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:24:0) after 1m0s
[04:51:55.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:24:0)
[04:51:55.055] Timer tick, asking Buildbucket for the build status
[04:51:55.173] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:55.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:25:0) after 1m0s
[04:52:55.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:25:0)
[04:52:55.177] Timer tick, asking Buildbucket for the build status
[04:52:55.497] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:55.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:26:0) after 1m0s
[04:53:55.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:26:0)
[04:53:55.610] Timer tick, asking Buildbucket for the build status
[04:53:55.682] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:55.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:27:0) after 1m0s
[04:54:55.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:27:0)
[04:54:55.732] Timer tick, asking Buildbucket for the build status
[04:54:55.867] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:28:0) after 1m0s
[04:55:55.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:28:0)
[04:55:55.866] Timer tick, asking Buildbucket for the build status
[04:55:55.983] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:55.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:29:0) after 1m0s
[04:56:55.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:29:0)
[04:56:55.969] Timer tick, asking Buildbucket for the build status
[04:56:56.052] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:56.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:30:0) after 1m0s
[04:57:56.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:30:0)
[04:57:56.088] Timer tick, asking Buildbucket for the build status
[04:57:56.543] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:56.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:31:0) after 1m0s
[04:58:56.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:31:0)
[04:58:56.615] Timer tick, asking Buildbucket for the build status
[04:58:57.331] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:57.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:32:0) after 1m0s
[04:59:57.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:32:0)
[04:59:57.479] Timer tick, asking Buildbucket for the build status
[04:59:57.801] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:57.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:33:0) after 1m0s
[05:00:57.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:33:0)
[05:00:57.931] Timer tick, asking Buildbucket for the build status
[05:00:58.033] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:58.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:34:0) after 1m0s
[05:01:58.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:34:0)
[05:01:58.207] Timer tick, asking Buildbucket for the build status
[05:01:58.286] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:58.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:35:0) after 1m0s
[05:02:58.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:35:0)
[05:02:58.363] Timer tick, asking Buildbucket for the build status
[05:02:58.497] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:58.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:36:0) after 1m0s
[05:03:58.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:36:0)
[05:03:58.703] Timer tick, asking Buildbucket for the build status
[05:03:58.959] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:58.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:37:0) after 1m0s
[05:04:59.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:37:0)
[05:04:59.311] Timer tick, asking Buildbucket for the build status
[05:04:59.444] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:59.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:38:0) after 1m0s
[05:05:59.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:38:0)
[05:05:59.508] Timer tick, asking Buildbucket for the build status
[05:05:59.635] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:59.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:39:0) after 1m0s
[05:06:59.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:39:0)
[05:06:59.707] Timer tick, asking Buildbucket for the build status
[05:06:59.772] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:59.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:40:0) after 1m0s
[05:07:59.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:40:0)
[05:07:59.790] Timer tick, asking Buildbucket for the build status
[05:07:59.887] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:59.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:41:0) after 1m0s
[05:08:59.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:41:0)
[05:08:59.905] Timer tick, asking Buildbucket for the build status
[05:09:00.166] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:00.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:42:0) after 1m0s
[05:10:00.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:42:0)
[05:10:00.255] Timer tick, asking Buildbucket for the build status
[05:10:00.361] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:00.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:43:0) after 1m0s
[05:11:00.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:43:0)
[05:11:00.378] Timer tick, asking Buildbucket for the build status
[05:11:00.487] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:00.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:44:0) after 1m0s
[05:12:00.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:44:0)
[05:12:00.548] Timer tick, asking Buildbucket for the build status
[05:12:00.635] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:00.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:45:0) after 1m0s
[05:13:00.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:45:0)
[05:13:00.654] Timer tick, asking Buildbucket for the build status
[05:13:00.738] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:00.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:46:0) after 1m0s
[05:14:00.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:46:0)
[05:14:00.865] Timer tick, asking Buildbucket for the build status
[05:14:01.255] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:01.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:47:0) after 1m0s
[05:15:01.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:47:0)
[05:15:01.355] Timer tick, asking Buildbucket for the build status
[05:15:01.461] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:01.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:48:0) after 1m0s
[05:16:01.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:48:0)
[05:16:01.479] Timer tick, asking Buildbucket for the build status
[05:16:01.608] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:01.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:49:0) after 1m0s
[05:17:01.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:49:0)
[05:17:01.836] Timer tick, asking Buildbucket for the build status
[05:17:01.918] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:01.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:50:0) after 1m0s
[05:18:02.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:50:0)
[05:18:02.163] Timer tick, asking Buildbucket for the build status
[05:18:02.254] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:02.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:51:0) after 1m0s
[05:19:02.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:51:0)
[05:19:02.372] Timer tick, asking Buildbucket for the build status
[05:19:02.543] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:02.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:52:0) after 1m0s
[05:20:02.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:52:0)
[05:20:02.562] Timer tick, asking Buildbucket for the build status
[05:20:02.684] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:02.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:53:0) after 1m0s
[05:21:02.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:53:0)
[05:21:02.792] Timer tick, asking Buildbucket for the build status
[05:21:02.915] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:02.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:54:0) after 1m0s
[05:22:03.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:54:0)
[05:22:03.002] Timer tick, asking Buildbucket for the build status
[05:22:03.132] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:03.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:55:0) after 1m0s
[05:23:03.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:55:0)
[05:23:03.172] Timer tick, asking Buildbucket for the build status
[05:23:03.259] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:03.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:56:0) after 1m0s
[05:24:03.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:56:0)
[05:24:03.352] Timer tick, asking Buildbucket for the build status
[05:24:03.531] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:03.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:57:0) after 1m0s
[05:25:03.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:57:0)
[05:25:03.567] Timer tick, asking Buildbucket for the build status
[05:25:03.707] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:03.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:58:0) after 1m0s
[05:26:03.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:58:0)
[05:26:03.859] Timer tick, asking Buildbucket for the build status
[05:26:04.038] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:04.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:59:0) after 1m0s
[05:27:04.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:59:0)
[05:27:04.128] Timer tick, asking Buildbucket for the build status
[05:27:04.529] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:04.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:60:0) after 1m0s
[05:28:04.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:60:0)
[05:28:04.533] Timer tick, asking Buildbucket for the build status
[05:28:04.617] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:04.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:61:0) after 1m0s
[05:29:04.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:61:0)
[05:29:04.635] Timer tick, asking Buildbucket for the build status
[05:29:04.807] Build 8861057792274338080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:04.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026487854454810480:62:0) after 1m0s
[05:29:59.362] Received PubSub notification, asking Buildbucket for the build status
[05:29:59.436] Build 8861057792274338080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:29:59.436] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607837398510061",
"created_by": "project:chromium",
"created_ts": "1607833789524576",
"id": "8861057792274338080",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a5dce97eab60586fc2b1c810dcd98892b0078329\"}}",
"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\": \"a5dce97eab60586fc2b1c810dcd98892b0078329\", \"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-us-central1-b-32-1-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16191, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4fb29e83e02c5152ebc60733eb5351ea98c7097d\", \"got_dawn_revision\": \"fb6c21547e6c5bc2053c15169769397e6584c9d2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a5dce97eab60586fc2b1c810dcd98892b0078329\", \"got_revision_cp\": \"refs/heads/master@{#836464}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"c16dc7107fd329b815c700b002d1ba40372b16dc\", \"got_v8_revision\": \"e7e9e702c110a535bb2daa6cd60db7b7d62d8665\", \"got_v8_revision_cp\": \"refs/heads/8.9.143@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"a5dce97eab60586fc2b1c810dcd98892b0078329\", \"swarm_hashes_refs/heads/master(at){#836464}_without_patch\": {\"absl_hardening_tests\": \"5080ae065846359a22385effb24aabf4039468fe\", \"accessibility_unittests\": \"638ebce2109271563d4cdf598466e2bb49ef314f\", \"angle_unittests\": \"6b0f5c0f3d306caf9ecdded96c66c835a56fb24b\", \"app_shell_unittests\": \"8f76cc8b9805fdcda940067b182c16c26233a204\", \"aura_unittests\": \"2aa7ac0f1bbd07c15f4aeef0aa9a6d88a2fc8b60\", \"base_unittests\": \"6fc17d7d38362fa4e5fd6310416ad3efceb226e0\", \"base_util_unittests\": \"6e2c10c5527443443c81920340face08da725661\", \"blink_common_unittests\": \"a5f783f1c5a4b21a105785919c1a0e4f463a626d\", \"blink_fuzzer_unittests\": \"5acbe83fae321d7daf4d4d895e2e7172131196e3\", \"blink_heap_unittests\": \"0edd0aa787176618782aea4cd9e9fd9c3365ed83\", \"blink_platform_unittests\": \"69df6a4772fdb1026be3a1cebd26b765f34e52ec\", \"blink_unittests\": \"879efa33b5c5d9afaf43eb07ff69c2a5c8ec60ed\", \"boringssl_crypto_tests\": \"f5b30c865c2a8aee262bb7941f2eec472c3011a4\", \"boringssl_ssl_tests\": \"67344d2de04294a61d405b87f675047b426e64da\", \"browser_switcher_bho_unittests\": \"ae61f3ac9abdfa72ad65137f7507868e6f221d38\", \"browser_tests\": \"bdf4165a1e3bbd8676898b87b2d601a34e3767de\", \"capture_unittests\": \"d0166e337280f2ca3181b77538cf532a2332be32\", \"cast_unittests\": \"2e3f42b76b2f458cc5154e4ecfe9652ad7a7b953\", \"cc_unittests\": \"891866c91e2c0209435d0a48293cee6c98fd152e\", \"chrome_app_unittests\": \"a31ff0595d65301a16c89902092eb045a358b59f\", \"chrome_cleaner_unittests\": \"6e96f99a45d6d8f86587c421612f40037f8bfd8b\", \"chrome_elf_unittests\": \"ca772c017970754181f354ca475b471ee9508f00\", \"chromedriver_unittests\": \"b1375e78f651f9eca33a599c3b8a96c527701c4b\", \"components_browsertests\": \"b95e827f11f682274350863170513dcd96bd7890\", \"components_unittests\": \"1d48a77aa56e8ecfc04a320cb758300d25942eda\", \"compositor_unittests\": \"82337556579371c1dd606d185bd296c7027e93d0\", \"content_browsertests\": \"b926f79be019aaa19a7beab96b5f1ab66f0f21e4\", \"content_unittests\": \"9c723617a56fc59f6c2eb48cc484d1af6cb3cc9d\", \"courgette_unittests\": \"79ae2b873177061932a79a0e0c4814c618cf9d12\", \"crashpad_tests\": \"edba5c7cb0d1becae84ffd59ef129d7fc462ad65\", \"cronet_tests\": \"eaaeaf5a10d417b2b273122136663d0096e4c49a\", \"cronet_unittests\": \"d01400586d20f5cab2c8d5a7c81fdff8e371ef07\", \"crypto_unittests\": \"2a5b17a9a9cfefeb69882874faa13c7715991655\", \"delayloads_unittests\": \"86ff8aac17f3b77890abcdf0f5f3aa8edfe7faff\", \"device_unittests\": \"f1482dc611973ce8376d3a2256353ff939915816\", \"display_unittests\": \"5fd82684d8aeadc878e82772b77b24e4c9156e33\", \"elevation_service_unittests\": \"9a06272ec42309cf7acd07d4061d516474cc636e\", \"events_unittests\": \"b4008930b67e7cfa398a4acb3c2ea32f922b544e\", \"extensions_browsertests\": \"658cfc85682068d8b3bbe32276063a6b4e7fe4f6\", \"extensions_unittests\": \"d70a94d92c8a1c7df336285d5f26daf3ea270a48\", \"filesystem_service_unittests\": \"3faf561d28ee547f1cf9fa053b2135f1e33fd4bc\", \"gcm_unit_tests\": \"613c7a970f2eff900e5b18be10dca042e75771e6\", \"gcp_unittests\": \"3b02602035a7b06356e84b6007ecc77604a5f7b0\", \"gfx_unittests\": \"27536ca10376903e56f0961c7277b6e7470a9995\", \"gin_unittests\": \"5d386ce26bc3d66d5391688e424b09f88b415377\", \"google_apis_unittests\": \"14ae91fcdb00f18362403a590c3523b2d1d86b8d\", \"gpu_unittests\": \"7f5e728fb4042a61a0aa832a055721489a5d7e82\", \"gwp_asan_unittests\": \"cf71fbeddb1b6aaaacbcd06de581dc0b93b2d0eb\", \"headless_browsertests\": \"a60754cf5f3a79417234701f165ce7b5a5fe645b\", \"headless_unittests\": \"f0d86c7e54601f933636eecced86f8c46b1c315e\", \"install_static_unittests\": \"83ff3ca9db599f55875712f30f12d436dac2a5e5\", \"installer_util_unittests\": \"c98cbeb0c805e925c7c4646b9c6b62c894e1ac4d\", \"interactive_ui_tests\": \"8571db5380de929f4da350cd219fe99652b35afa\", \"ipc_tests\": \"1d5761c8d149b70d4f40114211f3bf90a42d68fb\", \"jingle_unittests\": \"a0e599e7b68dcf21ad82923bcffb4a3937ccbe22\", \"latency_unittests\": \"71ae37ce177d0d5f5594f0ba95f6623a53c6cf2c\", \"libjingle_xmpp_unittests\": \"dff217b9ba1f69e901ffb2bc30d36977abd6ffb3\", \"liburlpattern_unittests\": \"e81d0d8c028c122f9d43070c05cda32ec167b099\", \"media_blink_unittests\": \"55c9505d5a4b3a716fefab8769ffefe117adae49\", \"media_unittests\": \"360b0c0a4cacd7443211088df9e48ad5bc23d745\", \"message_center_unittests\": \"45e311ccd4c51d52a730f210b74c8748249cd950\", \"midi_unittests\": \"60bcb9970f645a2cf32253c6247b9cb4ed9bc3ce\", \"mojo_core_unittests\": \"394cf2a036beab6ede8244883c8436b6609a0f35\", \"mojo_unittests\": \"29c340745aeec6397886ec1595afc4b0be60124b\", \"nacl_loader_unittests\": \"ad05b6ff2c23db7a862902dc997a108a7d32cfe2\", \"native_theme_unittests\": \"25d64f3e338aa0149cca813fef867327e9a5d864\", \"net_unittests\": \"16b249d6fb02e2a2683bfa99e1200469c2a17dd9\", \"notification_helper_unittests\": \"2ce70cd1d25ea75dc9501b32b33d44fca6ad4a13\", \"pdf_unittests\": \"4e21e07717fec2e287d1e09b3a2d1292e4f842cd\", \"perfetto_unittests\": \"96cb9f3a43a9d5f89c5ee82fba4fbceb5f781495\", \"ppapi_unittests\": \"b1c723d565b573c3118d8d91946ef3575f364feb\", \"printing_unittests\": \"c83fe7c22c7517034f06cf09c7a2e6b2a3d5897d\", \"remoting_unittests\": \"130c05dea1e0f8ad98842e8a40cc4df6fb7189c7\", \"sbox_integration_tests\": \"513a7eaa9697e8a80c3f1c206307ba929745f665\", \"sbox_unittests\": \"848958202cbb2f2447cda4332ffed4e2a63684ef\", \"sbox_validation_tests\": \"706d05d1d1d6f12237ce205afb5dc06be3a6dc5f\", \"service_manager_unittests\": \"ff75879480b31199a78e342f4ca26a0c622b9579\", \"services_unittests\": \"f539177e4174103e571fdeb3ba7e4f5d6754ed0e\", \"setup_unittests\": \"d60440ae1c402bbabe6c7845f2ae67bcad70c47c\", \"shell_dialogs_unittests\": \"c25a7c464deb3f04ed29da01f4bdea5c053ad039\", \"skia_unittests\": \"93bcb700ee44c470a8cc2f921608c07b35db018f\", \"snapshot_unittests\": \"975ea7675a165144c764455ca64ed04fa91470c6\", \"sql_unittests\": \"661e8a0be394e6ba8bbc49e63dd5cb98b760f4b9\", \"storage_unittests\": \"d589d3877ea470bdcd23428d27a7821af701894c\", \"sync_integration_tests\": \"564a26bbcf3307eb72ad8a74d189d8a570a8ef47\", \"traffic_annotation_auditor_unittests\": \"a0f80eacd761cf484320126791015ec38319a9fa\", \"ui_base_unittests\": \"ef544c60ddd22895310d5ba8fda96e3c77ce69a6\", \"ui_touch_selection_unittests\": \"e058f612f99276948fe95f80e1d53844c918781c\", \"unit_tests\": \"4b62b24d31a5cc55ca3167255a1acd06b4020fec\", \"updater_tests\": \"0bd830410b6cd04c9f90ae8df526e06d6926d76e\", \"url_unittests\": \"b793d002d82a9af1c868bc5874a0af725b4b1742\", \"views_unittests\": \"6957c38e74b68dcfbec62bbd37906720f4f1cf8d\", \"viz_unittests\": \"61f08ff6414ebdf9a0a3d2384b8ef4ce3deac50e\", \"vr_common_unittests\": \"1223cc3825ab70468ba598bae2946e6348f113cb\", \"vr_pixeltests\": \"65462f2002152773958db65ba4134766884b354a\", \"weblayer_browsertests\": \"1af3451d79b1eb563a5642bf4f569b36243f8fac\", \"weblayer_unittests\": \"e8e96bf6738457fe422bede6fa695cddfb3258a1\", \"wm_unittests\": \"4b313fc7b5970b1907a12e64a56f53c0ba591202\", \"wtf_unittests\": \"ee12ecfef4fbf760c8585a3a2f94c2d83a8fd5d2\", \"zlib_unittests\": \"99cccb560471e8ebfe032706a0e2fb27e4c25ea2\", \"zucchini_unittests\": \"39502f0d9f1b8dca46f542527f7344c7df89c499\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-qsbv\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607833792063825",
"status": "COMPLETED",
"status_changed_ts": "1607837398787115",
"tags": [
"build_address:luci.chromium.ci/win-asan/16191",
"builder:win-asan",
"buildset:commit/git/a5dce97eab60586fc2b1c810dcd98892b0078329",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5dce97eab60586fc2b1c810dcd98892b0078329",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026487854454810480",
"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/8861057792274338080/+/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:50732e2fe65d7810",
"user_agent:luci-scheduler"
],
"updated_ts": "1607837398791392",
"url": "https://ci.chromium.org/b/8861057792274338080",
"utcnow_ts": "1607837399427280"
}
}
[05:29:59.436] Invocation finished in 1h0m11.374592511s with status SUCCEEDED