[02:39:54.152] New invocation is queued and will start shortly
[02:39:55.230] Starting the invocation (attempt 1)
[02:39:55.265] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:39:55.265] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:39:55.265] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023414471830156064",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2b1ac735e8527d1d911cfea1789da337c4accceb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023414471830156064",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2b1ac735e8527d1d911cfea1789da337c4accceb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2b1ac735e8527d1d911cfea1789da337c4accceb",
"gitiles_ref:refs/heads/master"
]
}
[02:39:55.925] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610764795353515",
"id": "8857984409906417472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2b1ac735e8527d1d911cfea1789da337c4accceb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610764795704094",
"tags": [
"build_address:luci.chromium.ci/win-asan/16849",
"builder:win-asan",
"buildset:commit/git/2b1ac735e8527d1d911cfea1789da337c4accceb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2b1ac735e8527d1d911cfea1789da337c4accceb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023414471830156064",
"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/8857984409906417472/+/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": "1610764795704207",
"url": "https://ci.chromium.org/b/8857984409906417472",
"utcnow_ts": "1610764795918919"
}
}
[02:39:55.925] Task URL: https://ci.chromium.org/b/8857984409906417472
[02:39:55.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:2:0) after 1m0s
[02:39:57.010] Received PubSub notification, asking Buildbucket for the build status
[02:39:57.356] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:55.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:2:0)
[02:40:55.903] Timer tick, asking Buildbucket for the build status
[02:40:56.106] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:56.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:4:0) after 1m0s
[02:41:56.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:4:0)
[02:41:56.148] Timer tick, asking Buildbucket for the build status
[02:41:56.257] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:56.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:5:0) after 1m0s
[02:42:56.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:5:0)
[02:42:56.303] Timer tick, asking Buildbucket for the build status
[02:42:56.394] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:56.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:6:0) after 1m0s
[02:43:56.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:6:0)
[02:43:56.446] Timer tick, asking Buildbucket for the build status
[02:43:56.996] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:56.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:7:0) after 1m0s
[02:44:57.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:7:0)
[02:44:57.040] Timer tick, asking Buildbucket for the build status
[02:44:57.318] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:57.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:8:0) after 1m0s
[02:45:57.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:8:0)
[02:45:57.440] Timer tick, asking Buildbucket for the build status
[02:45:57.609] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:9:0) after 1m0s
[02:46:57.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:9:0)
[02:46:57.603] Timer tick, asking Buildbucket for the build status
[02:46:57.987] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:57.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:10:0) after 1m0s
[02:47:58.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:10:0)
[02:47:58.044] Timer tick, asking Buildbucket for the build status
[02:47:58.104] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:58.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:11:0) after 1m0s
[02:48:58.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:11:0)
[02:48:58.152] Timer tick, asking Buildbucket for the build status
[02:48:58.202] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:58.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:12:0) after 1m0s
[02:49:58.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:12:0)
[02:49:58.253] Timer tick, asking Buildbucket for the build status
[02:49:58.347] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:58.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:13:0) after 1m0s
[02:50:58.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:13:0)
[02:50:58.369] Timer tick, asking Buildbucket for the build status
[02:50:58.432] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:58.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:14:0) after 1m0s
[02:51:58.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:14:0)
[02:51:58.476] Timer tick, asking Buildbucket for the build status
[02:51:58.532] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:58.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:15:0) after 1m0s
[02:52:58.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:15:0)
[02:52:58.588] Timer tick, asking Buildbucket for the build status
[02:52:58.663] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:16:0) after 1m0s
[02:53:58.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:16:0)
[02:53:58.663] Timer tick, asking Buildbucket for the build status
[02:53:58.718] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:17:0) after 1m0s
[02:54:58.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:17:0)
[02:54:58.729] Timer tick, asking Buildbucket for the build status
[02:54:58.835] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:58.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:18:0) after 1m0s
[02:55:58.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:18:0)
[02:55:58.877] Timer tick, asking Buildbucket for the build status
[02:55:58.932] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:58.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:19:0) after 1m0s
[02:56:58.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:19:0)
[02:56:58.963] Timer tick, asking Buildbucket for the build status
[02:56:59.087] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:59.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:20:0) after 1m0s
[02:57:59.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:20:0)
[02:57:59.111] Timer tick, asking Buildbucket for the build status
[02:57:59.237] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:59.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:21:0) after 1m0s
[02:58:59.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:21:0)
[02:58:59.263] Timer tick, asking Buildbucket for the build status
[02:58:59.330] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:59.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:22:0) after 1m0s
[02:59:59.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:22:0)
[02:59:59.359] Timer tick, asking Buildbucket for the build status
[02:59:59.669] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:59.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:23:0) after 1m0s
[03:00:59.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:23:0)
[03:00:59.726] Timer tick, asking Buildbucket for the build status
[03:00:59.851] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:24:0) after 1m0s
[03:01:59.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:24:0)
[03:01:59.880] Timer tick, asking Buildbucket for the build status
[03:01:59.987] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:59.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:25:0) after 1m0s
[03:03:00.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:25:0)
[03:03:00.015] Timer tick, asking Buildbucket for the build status
[03:03:00.367] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:00.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:26:0) after 1m0s
[03:04:00.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:26:0)
[03:04:00.379] Timer tick, asking Buildbucket for the build status
[03:04:00.520] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:00.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:27:0) after 1m0s
[03:05:00.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:27:0)
[03:05:00.527] Timer tick, asking Buildbucket for the build status
[03:05:00.631] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:00.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:28:0) after 1m0s
[03:06:00.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:28:0)
[03:06:00.987] Timer tick, asking Buildbucket for the build status
[03:06:01.161] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:01.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:29:0) after 1m0s
[03:07:01.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:29:0)
[03:07:01.687] Timer tick, asking Buildbucket for the build status
[03:07:01.815] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:01.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:30:0) after 1m0s
[03:08:01.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:30:0)
[03:08:01.898] Timer tick, asking Buildbucket for the build status
[03:08:02.075] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:02.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:31:0) after 1m0s
[03:09:02.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:31:0)
[03:09:02.098] Timer tick, asking Buildbucket for the build status
[03:09:02.502] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:02.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:32:0) after 1m0s
[03:10:02.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:32:0)
[03:10:02.517] Timer tick, asking Buildbucket for the build status
[03:10:03.108] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:03.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:33:0) after 1m0s
[03:11:03.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:33:0)
[03:11:03.224] Timer tick, asking Buildbucket for the build status
[03:11:03.850] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:03.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:34:0) after 1m0s
[03:12:03.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:34:0)
[03:12:03.940] Timer tick, asking Buildbucket for the build status
[03:12:04.061] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:35:0) after 1m0s
[03:13:04.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:35:0)
[03:13:04.113] Timer tick, asking Buildbucket for the build status
[03:13:04.563] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:04.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:36:0) after 1m0s
[03:14:04.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:36:0)
[03:14:04.648] Timer tick, asking Buildbucket for the build status
[03:14:05.030] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:05.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:37:0) after 1m0s
[03:15:05.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:37:0)
[03:15:05.289] Timer tick, asking Buildbucket for the build status
[03:15:05.427] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:05.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:38:0) after 1m0s
[03:16:05.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:38:0)
[03:16:05.757] Timer tick, asking Buildbucket for the build status
[03:16:05.918] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:05.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:39:0) after 1m0s
[03:17:05.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:39:0)
[03:17:05.985] Timer tick, asking Buildbucket for the build status
[03:17:06.221] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:06.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:40:0) after 1m0s
[03:18:06.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:40:0)
[03:18:06.304] Timer tick, asking Buildbucket for the build status
[03:18:06.484] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:06.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:41:0) after 1m0s
[03:19:06.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:41:0)
[03:19:06.572] Timer tick, asking Buildbucket for the build status
[03:19:06.663] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:06.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:42:0) after 1m0s
[03:20:06.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:42:0)
[03:20:06.816] Timer tick, asking Buildbucket for the build status
[03:20:06.970] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:06.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:43:0) after 1m0s
[03:21:07.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:43:0)
[03:21:07.084] Timer tick, asking Buildbucket for the build status
[03:21:07.163] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:07.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:44:0) after 1m0s
[03:22:07.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:44:0)
[03:22:07.230] Timer tick, asking Buildbucket for the build status
[03:22:07.336] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:07.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:45:0) after 1m0s
[03:23:07.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:45:0)
[03:23:07.363] Timer tick, asking Buildbucket for the build status
[03:23:07.482] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:07.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:46:0) after 1m0s
[03:24:07.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:46:0)
[03:24:07.547] Timer tick, asking Buildbucket for the build status
[03:24:07.717] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:07.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:47:0) after 1m0s
[03:25:07.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:47:0)
[03:25:07.746] Timer tick, asking Buildbucket for the build status
[03:25:07.868] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:07.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:48:0) after 1m0s
[03:26:07.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:48:0)
[03:26:07.896] Timer tick, asking Buildbucket for the build status
[03:26:08.021] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:08.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:49:0) after 1m0s
[03:27:08.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:49:0)
[03:27:08.137] Timer tick, asking Buildbucket for the build status
[03:27:08.438] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:08.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:50:0) after 1m0s
[03:28:08.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:50:0)
[03:28:08.616] Timer tick, asking Buildbucket for the build status
[03:28:08.687] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:08.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:51:0) after 1m0s
[03:29:08.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:51:0)
[03:29:08.792] Timer tick, asking Buildbucket for the build status
[03:29:09.065] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:09.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:52:0) after 1m0s
[03:30:09.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:52:0)
[03:30:09.098] Timer tick, asking Buildbucket for the build status
[03:30:09.237] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:09.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:53:0) after 1m0s
[03:31:09.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:53:0)
[03:31:09.358] Timer tick, asking Buildbucket for the build status
[03:31:09.486] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:09.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:54:0) after 1m0s
[03:32:09.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:54:0)
[03:32:09.617] Timer tick, asking Buildbucket for the build status
[03:32:09.715] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:55:0) after 1m0s
[03:33:10.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:55:0)
[03:33:10.850] Timer tick, asking Buildbucket for the build status
[03:33:11.580] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:11.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:56:0) after 1m0s
[03:34:11.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:56:0)
[03:34:11.628] Timer tick, asking Buildbucket for the build status
[03:34:11.736] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:57:0) after 1m0s
[03:35:11.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:57:0)
[03:35:11.738] Timer tick, asking Buildbucket for the build status
[03:35:12.041] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:12.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:58:0) after 1m0s
[03:36:12.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:58:0)
[03:36:12.145] Timer tick, asking Buildbucket for the build status
[03:36:12.281] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:12.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:59:0) after 1m0s
[03:37:12.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:59:0)
[03:37:12.316] Timer tick, asking Buildbucket for the build status
[03:37:12.397] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:12.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:60:0) after 1m0s
[03:38:12.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:60:0)
[03:38:12.541] Timer tick, asking Buildbucket for the build status
[03:38:12.699] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:12.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:61:0) after 1m0s
[03:39:12.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:61:0)
[03:39:12.797] Timer tick, asking Buildbucket for the build status
[03:39:13.131] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:13.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:62:0) after 1m0s
[03:40:13.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:62:0)
[03:40:13.215] Timer tick, asking Buildbucket for the build status
[03:40:14.028] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:14.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:63:0) after 1m0s
[03:41:14.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:63:0)
[03:41:14.101] Timer tick, asking Buildbucket for the build status
[03:41:14.174] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:14.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:64:0) after 1m0s
[03:42:14.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:64:0)
[03:42:14.222] Timer tick, asking Buildbucket for the build status
[03:42:14.339] Build 8857984409906417472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:14.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023414471830156064:65:0) after 1m0s
[03:42:48.575] Received PubSub notification, asking Buildbucket for the build status
[03:42:48.904] Build 8857984409906417472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:42:48.906] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610768567904581",
"created_by": "project:chromium",
"created_ts": "1610764795353515",
"id": "8857984409906417472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2b1ac735e8527d1d911cfea1789da337c4accceb\"}}",
"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\": \"2b1ac735e8527d1d911cfea1789da337c4accceb\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-aayu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16849, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"8f5ca26678c12d54d2981a84e48a5304746ec824\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"2b1ac735e8527d1d911cfea1789da337c4accceb\", \"got_revision_cp\": \"refs/heads/master@{#844344}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"2b1ac735e8527d1d911cfea1789da337c4accceb\", \"swarm_hashes_refs/heads/master(at){#844344}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"a15114cfc52c9a540ca187b797e988cb7f449a44\", \"angle_unittests\": \"3fb5158e87bd0b12f582f6e050f03ce883acb886\", \"app_shell_unittests\": \"854305a901ae4ad76fa864b25d2373ef202d4f66\", \"aura_unittests\": \"08d227788b6092e591534ab4cd277bca391ca18a\", \"base_unittests\": \"e1086fde7c773809e056e368865fa2b57c0df5ae\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"1ea9f9d2218c2a2f5df0ac59dd8615967a4bf195\", \"blink_fuzzer_unittests\": \"649227a0607eda856d2188738727c173594e42b6\", \"blink_heap_unittests\": \"4d87b10435afe7d1f2a25490b0849318ead9dea4\", \"blink_platform_unittests\": \"542a73e8ab6e3c6a8a58f8d54c825bfe4cdbd11b\", \"blink_unittests\": \"693961e2dbcb0c4e990d84126e0dfa467cba98e2\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"d7ad2b79420dc746899c864e302cd5c9d4b36b06\", \"capture_unittests\": \"c1752a1fd5a51262a8899d8452228f1e952e9e62\", \"cast_unittests\": \"ec66776b187143ed12525af099830d8a01420014\", \"cc_unittests\": \"01c84115d5e568db2b0af89ea503e7f96ddea4e9\", \"chrome_app_unittests\": \"737f9e36132ac2e901d8a053fa8752389b5693e7\", \"chrome_cleaner_unittests\": \"932667402f534a8deea15ea5284a79e175d06015\", \"chrome_elf_unittests\": \"95d4e020c6ddd931e979b2019e75dd78afa76111\", \"chromedriver_unittests\": \"00ecd24024acd53a5675e89cd615fff259d2d739\", \"components_browsertests\": \"76c74653db929c5453a283d80284b8b2a08a7d7e\", \"components_unittests\": \"96193db40fe420dd0af58a5e57e9ab5d62ae2eb7\", \"compositor_unittests\": \"d08b7400603d94b92171f9742c501561b883012f\", \"content_browsertests\": \"dc7e2c4e246a5d60bc78085574e290b5382e05fb\", \"content_unittests\": \"dd22535ac0ea88956c8455a7de9ca954dec11b68\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"de06c729b681fe87c009e1a8d2c347fa7eb16327\", \"cronet_unittests\": \"34027c03c9fc62467d6c73f779f4ee65dd2c033d\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"1972f16e5b699caa03b448961cb4d78e95822743\", \"device_unittests\": \"6c1f5e5059bcd2669b1d340f76714be1f3097a30\", \"display_unittests\": \"89c38c0df3ef86dbeadd56f9c2fc5b81dcf8367d\", \"elevation_service_unittests\": \"532425400b167c90332181602e89758f64b3d5e3\", \"events_unittests\": \"c48783e5fae3804d40a77c007219c80d7eef0511\", \"extensions_browsertests\": \"564e3377d811b53994c8395c92808a4f05a1d74a\", \"extensions_unittests\": \"ee3e8437231a5a130d7b54eab749cb1624ced560\", \"filesystem_service_unittests\": \"1a0a90ec0e5e56ae3e1106d44d642ed86eb571b7\", \"gcm_unit_tests\": \"422435c83e5f17addc11bf730db4ae7cad6a6596\", \"gcp_unittests\": \"741359a89e00fc5b3b39f2cdc36b6230bfc2d915\", \"gfx_unittests\": \"701e56ba41bc9a188ebfdd1e0afcb40a08c31774\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"f0e308d3687395f4647dec1bcccdd386d7983c18\", \"gpu_unittests\": \"937944bc365e4f64a27839e5b62df2684e8503f6\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"2d3461d11efa0139d062aca06df9d12a0c41c87a\", \"headless_unittests\": \"9ab7d110c5a685cf3a1af7c309a71efc10198d66\", \"install_static_unittests\": \"d49b57e36d9f580b2207b3a09bab7dc64c1c508a\", \"installer_util_unittests\": \"3f456e030b35d1ee8baf0b5634474690ccf2d5da\", \"interactive_ui_tests\": \"56caf3035ca8ed1a8ebe5fb3c2bc92d8cb13060f\", \"ipc_tests\": \"04b29ea2dcac58398dc589b81059bb615997fce1\", \"jingle_unittests\": \"672521e7f22dae8838849335cbde39e79c509fb3\", \"latency_unittests\": \"9401166acfd7bfa522ee2e07bcce83babc474488\", \"libjingle_xmpp_unittests\": \"894a6d24b059fb7dfad8dd1d6b9bfeecf47f4e57\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"cb666b07eb8b9522bf87287a6bcc3c2f61acb20a\", \"media_unittests\": \"8aea6f3c502a296234a45792a508d6f19c77697a\", \"message_center_unittests\": \"49cedf6dcd78d8be80998d2542a0d4cdc53800bb\", \"midi_unittests\": \"63ef9c56554fdcd74f5124fb04fc6dcc32676df1\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"94a819075f442a996fd2648aa4b5764a2117f637\", \"nacl_loader_unittests\": \"9ad4ad8b622f981aa51a3b066f7d995e7bc9bcd5\", \"native_theme_unittests\": \"59f4cc7059cdcd07f3084e4637469185d33ab8d6\", \"net_unittests\": \"48ff206d05ae9f55c4242d02b610fd8b4ead46ae\", \"notification_helper_unittests\": \"00db182b249b14d0eece5eb115198663ddbb7116\", \"pdf_unittests\": \"68f5814d6fc04527fedea221809d6e6590092b86\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"677165aadaec4972b77c9ef9ac2c8892bc4d2491\", \"printing_unittests\": \"eef0293e6b2b416a8ffd4129934d209b204c8a41\", \"remoting_unittests\": \"588437e4a3f8f05420566b84fc183f30039571aa\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"742fb66769747ffa9b49e9fd7cd548550f9be44f\", \"services_unittests\": \"04ebc79ece8f96f166fc7d727bfd872707b1fb44\", \"setup_unittests\": \"d9fd5d1d6e67e6f079ae3a194d1da3f228ce7b28\", \"shell_dialogs_unittests\": \"1ad5898183de980a2bef5217427d574789a097f1\", \"skia_unittests\": \"b7f0ff7c8b769852154c3c42526e43af8eda08b6\", \"snapshot_unittests\": \"7bfe9055faccac5a7bed55d595f50787dec4cd1d\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"530af52c88a9c8c851fb030ab2d2ee65540836f8\", \"sync_integration_tests\": \"6baf62ae9097c33e82dfec2e41810f183b3c8b94\", \"traffic_annotation_auditor_unittests\": \"5ca5b0dfb6e577335278e6a58d35ac57539b9360\", \"ui_base_unittests\": \"649a776a8207207590d4637da8a474fb97a59ff9\", \"ui_touch_selection_unittests\": \"08e03c4eca771e15bb9dee2c33f79d7d782cd519\", \"unit_tests\": \"52abcf625e3463b3d9adfa8e677c0dd98049bf7a\", \"updater_tests\": \"4cd547192b51b61a15b8e4deffad0c9ee1b003b8\", \"url_unittests\": \"ca5c3c0079d3547037355463520ce302894f4548\", \"views_unittests\": \"ec40e04be4b13828fbef67bd9b8d882963552a74\", \"viz_unittests\": \"00078c98412d172b3605454c0ac4c26de79373f1\", \"vr_common_unittests\": \"c8f0af25d2d964c93281c5caeb3ae606af0fb3c2\", \"vr_pixeltests\": \"c7648e632bb607346f9172380dd2e0ea30945093\", \"weblayer_browsertests\": \"200f0dbe06dbe8d89449cd37667d4a7e6800d47c\", \"weblayer_unittests\": \"7efea08749a10be531b9a5f2a6fd8f9941e28efc\", \"wm_unittests\": \"0dbe409794d859954ead6d0b6465e6f8565bbefd\", \"wtf_unittests\": \"c91ec5888a9620ac475a73c7d07063dbc80b4076\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-aayu\"], \"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": "1610764796168418",
"status": "COMPLETED",
"status_changed_ts": "1610768568164412",
"tags": [
"build_address:luci.chromium.ci/win-asan/16849",
"builder:win-asan",
"buildset:commit/git/2b1ac735e8527d1d911cfea1789da337c4accceb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2b1ac735e8527d1d911cfea1789da337c4accceb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023414471830156064",
"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/8857984409906417472/+/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:5121e1c7e4c0b910",
"user_agent:luci-scheduler"
],
"updated_ts": "1610768568168662",
"url": "https://ci.chromium.org/b/8857984409906417472",
"utcnow_ts": "1610768568880708"
}
}
[03:42:48.906] Invocation finished in 1h2m54.770723887s with status SUCCEEDED