[18:55:50.585] New invocation is queued and will start shortly
[18:55:51.690] Starting the invocation (attempt 1)
[18:55:51.729] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:55:51.729] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:55:51.729] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024259040623616016",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024259040623616016",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"gitiles_ref:refs/heads/master"
]
}
[18:55:53.089] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609959351875257",
"id": "8858828978606906816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609959352756708",
"tags": [
"build_address:luci.chromium.ci/win-asan/16658",
"builder:win-asan",
"buildset:commit/git/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024259040623616016",
"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/8858828978606906816/+/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": "1609959352756903",
"url": "https://ci.chromium.org/b/8858828978606906816",
"utcnow_ts": "1609959353077692"
}
}
[18:55:53.090] Task URL: https://ci.chromium.org/b/8858828978606906816
[18:55:53.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:2:0) after 1m0s
[18:55:55.700] Received PubSub notification, asking Buildbucket for the build status
[18:55:56.209] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:53.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:2:0)
[18:56:53.215] Timer tick, asking Buildbucket for the build status
[18:56:53.654] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:53.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:4:0) after 1m0s
[18:57:53.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:4:0)
[18:57:53.676] Timer tick, asking Buildbucket for the build status
[18:57:53.957] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:53.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:5:0) after 1m0s
[18:58:54.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:5:0)
[18:58:54.156] Timer tick, asking Buildbucket for the build status
[18:58:54.367] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:54.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:6:0) after 1m0s
[18:59:54.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:6:0)
[18:59:54.820] Timer tick, asking Buildbucket for the build status
[18:59:56.109] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:56.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:7:0) after 1m0s
[19:00:56.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:7:0)
[19:00:56.164] Timer tick, asking Buildbucket for the build status
[19:00:56.352] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:56.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:8:0) after 1m0s
[19:01:56.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:8:0)
[19:01:56.811] Timer tick, asking Buildbucket for the build status
[19:01:57.095] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:57.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:9:0) after 1m0s
[19:02:57.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:9:0)
[19:02:57.297] Timer tick, asking Buildbucket for the build status
[19:02:57.398] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:57.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:10:0) after 1m0s
[19:03:57.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:10:0)
[19:03:57.570] Timer tick, asking Buildbucket for the build status
[19:03:57.764] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:57.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:11:0) after 1m0s
[19:04:57.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:11:0)
[19:04:57.935] Timer tick, asking Buildbucket for the build status
[19:04:58.127] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:58.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:12:0) after 1m0s
[19:05:58.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:12:0)
[19:05:58.229] Timer tick, asking Buildbucket for the build status
[19:05:58.497] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:58.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:13:0) after 1m0s
[19:06:58.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:13:0)
[19:06:58.714] Timer tick, asking Buildbucket for the build status
[19:06:58.784] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:58.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:14:0) after 1m0s
[19:07:59.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:14:0)
[19:07:59.042] Timer tick, asking Buildbucket for the build status
[19:07:59.273] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:59.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:15:0) after 1m0s
[19:08:59.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:15:0)
[19:08:59.465] Timer tick, asking Buildbucket for the build status
[19:08:59.634] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:59.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:16:0) after 1m0s
[19:09:59.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:16:0)
[19:09:59.709] Timer tick, asking Buildbucket for the build status
[19:10:00.151] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:00.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:17:0) after 1m0s
[19:11:00.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:17:0)
[19:11:00.221] Timer tick, asking Buildbucket for the build status
[19:11:00.447] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:00.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:18:0) after 1m0s
[19:12:00.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:18:0)
[19:12:00.597] Timer tick, asking Buildbucket for the build status
[19:12:01.451] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:19:0) after 1m0s
[19:13:02.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:19:0)
[19:13:02.107] Timer tick, asking Buildbucket for the build status
[19:13:02.331] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:02.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:20:0) after 1m0s
[19:14:02.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:20:0)
[19:14:02.720] Timer tick, asking Buildbucket for the build status
[19:14:02.972] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:02.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:21:0) after 1m0s
[19:15:03.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:21:0)
[19:15:03.033] Timer tick, asking Buildbucket for the build status
[19:15:03.469] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:03.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:22:0) after 1m0s
[19:16:03.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:22:0)
[19:16:03.519] Timer tick, asking Buildbucket for the build status
[19:16:04.982] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:04.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:23:0) after 1m0s
[19:17:05.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:23:0)
[19:17:05.067] Timer tick, asking Buildbucket for the build status
[19:17:05.293] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:05.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:24:0) after 1m0s
[19:18:05.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:24:0)
[19:18:05.317] Timer tick, asking Buildbucket for the build status
[19:18:05.480] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:05.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:25:0) after 1m0s
[19:19:05.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:25:0)
[19:19:05.592] Timer tick, asking Buildbucket for the build status
[19:19:06.046] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:06.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:26:0) after 1m0s
[19:20:06.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:26:0)
[19:20:06.258] Timer tick, asking Buildbucket for the build status
[19:20:06.387] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:06.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:27:0) after 1m0s
[19:21:06.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:27:0)
[19:21:06.638] Timer tick, asking Buildbucket for the build status
[19:21:06.909] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:06.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:28:0) after 1m0s
[19:22:06.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:28:0)
[19:22:06.963] Timer tick, asking Buildbucket for the build status
[19:22:07.795] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:07.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:29:0) after 1m0s
[19:23:07.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:29:0)
[19:23:07.814] Timer tick, asking Buildbucket for the build status
[19:23:08.091] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:08.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:30:0) after 1m0s
[19:24:08.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:30:0)
[19:24:08.393] Timer tick, asking Buildbucket for the build status
[19:24:08.670] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:08.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:31:0) after 1m0s
[19:25:08.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:31:0)
[19:25:08.910] Timer tick, asking Buildbucket for the build status
[19:25:09.688] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:09.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:32:0) after 1m0s
[19:26:09.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:32:0)
[19:26:09.734] Timer tick, asking Buildbucket for the build status
[19:26:10.149] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:10.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:33:0) after 1m0s
[19:27:10.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:33:0)
[19:27:10.327] Timer tick, asking Buildbucket for the build status
[19:27:10.720] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:10.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:34:0) after 1m0s
[19:28:10.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:34:0)
[19:28:10.746] Timer tick, asking Buildbucket for the build status
[19:28:11.761] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:11.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:35:0) after 1m0s
[19:29:11.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:35:0)
[19:29:11.978] Timer tick, asking Buildbucket for the build status
[19:29:13.072] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:13.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:36:0) after 1m0s
[19:30:13.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:36:0)
[19:30:13.112] Timer tick, asking Buildbucket for the build status
[19:30:13.644] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:13.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:37:0) after 1m0s
[19:31:13.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:37:0)
[19:31:13.660] Timer tick, asking Buildbucket for the build status
[19:31:14.263] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:14.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:38:0) after 1m0s
[19:32:14.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:38:0)
[19:32:14.504] Timer tick, asking Buildbucket for the build status
[19:32:15.186] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:15.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:39:0) after 1m0s
[19:33:15.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:39:0)
[19:33:15.337] Timer tick, asking Buildbucket for the build status
[19:33:15.434] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:15.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:40:0) after 1m0s
[19:34:15.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:40:0)
[19:34:15.844] Timer tick, asking Buildbucket for the build status
[19:34:16.115] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:16.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:41:0) after 1m0s
[19:35:16.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:41:0)
[19:35:16.390] Timer tick, asking Buildbucket for the build status
[19:35:16.810] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:16.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:42:0) after 1m0s
[19:36:17.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:42:0)
[19:36:17.259] Timer tick, asking Buildbucket for the build status
[19:36:17.372] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:17.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:43:0) after 1m0s
[19:37:17.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:43:0)
[19:37:17.419] Timer tick, asking Buildbucket for the build status
[19:37:18.095] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:18.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:44:0) after 1m0s
[19:38:18.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:44:0)
[19:38:18.268] Timer tick, asking Buildbucket for the build status
[19:38:18.664] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:45:0) after 1m0s
[19:39:18.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:45:0)
[19:39:18.852] Timer tick, asking Buildbucket for the build status
[19:39:19.369] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:19.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:46:0) after 1m0s
[19:40:19.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:46:0)
[19:40:19.475] Timer tick, asking Buildbucket for the build status
[19:40:19.608] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:47:0) after 1m0s
[19:41:19.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:47:0)
[19:41:19.779] Timer tick, asking Buildbucket for the build status
[19:41:19.914] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:19.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:48:0) after 1m0s
[19:42:20.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:48:0)
[19:42:20.139] Timer tick, asking Buildbucket for the build status
[19:42:20.515] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:20.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:49:0) after 1m0s
[19:43:20.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:49:0)
[19:43:20.595] Timer tick, asking Buildbucket for the build status
[19:43:21.044] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:21.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:50:0) after 1m0s
[19:44:21.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:50:0)
[19:44:21.108] Timer tick, asking Buildbucket for the build status
[19:44:22.763] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:22.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:51:0) after 1m0s
[19:45:23.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:51:0)
[19:45:23.035] Timer tick, asking Buildbucket for the build status
[19:45:23.204] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:23.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:52:0) after 1m0s
[19:46:23.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:52:0)
[19:46:23.562] Timer tick, asking Buildbucket for the build status
[19:46:23.900] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:23.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:53:0) after 1m0s
[19:47:23.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:53:0)
[19:47:23.975] Timer tick, asking Buildbucket for the build status
[19:47:24.136] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:24.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:54:0) after 1m0s
[19:48:24.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:54:0)
[19:48:24.195] Timer tick, asking Buildbucket for the build status
[19:48:24.353] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:24.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:55:0) after 1m0s
[19:49:24.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:55:0)
[19:49:24.454] Timer tick, asking Buildbucket for the build status
[19:49:24.544] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:24.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:56:0) after 1m0s
[19:50:24.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:56:0)
[19:50:24.571] Timer tick, asking Buildbucket for the build status
[19:50:24.676] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:24.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:57:0) after 1m0s
[19:51:24.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:57:0)
[19:51:24.688] Timer tick, asking Buildbucket for the build status
[19:51:24.894] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:24.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:58:0) after 1m0s
[19:52:24.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:58:0)
[19:52:24.950] Timer tick, asking Buildbucket for the build status
[19:52:25.099] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:25.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:59:0) after 1m0s
[19:53:25.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:59:0)
[19:53:25.424] Timer tick, asking Buildbucket for the build status
[19:53:25.619] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:25.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:60:0) after 1m0s
[19:54:25.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:60:0)
[19:54:25.991] Timer tick, asking Buildbucket for the build status
[19:54:26.094] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:61:0) after 1m0s
[19:55:26.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:61:0)
[19:55:26.150] Timer tick, asking Buildbucket for the build status
[19:55:26.536] Build 8858828978606906816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:26.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024259040623616016:62:0) after 1m0s
[19:55:44.249] Received PubSub notification, asking Buildbucket for the build status
[19:55:44.421] Build 8858828978606906816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:55:44.421] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609962941967650",
"created_by": "project:chromium",
"created_ts": "1609959351875257",
"id": "8858828978606906816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\"}}",
"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\": \"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\", \"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-juf7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16658, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"a8b962b78978def21ccccc8db00581e810fa4354\", \"got_dawn_revision\": \"a84acc4fcca80d80c379e3b02d8dc0f7b102ba9d\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\", \"got_revision_cp\": \"refs/heads/master@{#840690}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"eb3c1fae634c7db0176b70ed50a5964cd39d67d1\", \"swarm_hashes_refs/heads/master(at){#840690}_without_patch\": {\"absl_hardening_tests\": \"6c63c3b2a99f0dab4c9d1998d496e0d36fd5d00c\", \"accessibility_unittests\": \"c1ff854ec07da2ecb8f023f5afd41041d45b78a4\", \"angle_unittests\": \"698af5ec3a5d27b1a99911e82fd4a275c2fd683b\", \"app_shell_unittests\": \"ff2f8a1a6324bdbf663e0dcacc2bfddca1acd573\", \"aura_unittests\": \"d6f4af7b88e28cd4aa30218cbedaebf553173586\", \"base_unittests\": \"3680e210d32bb542ecc2aa0f1160d3215960ee0a\", \"base_util_unittests\": \"1db728ae4e3deb48921f55b04091dabbf36b7f68\", \"blink_common_unittests\": \"642974c97041d264efab43198f8a4afe13f8ad35\", \"blink_fuzzer_unittests\": \"ead77e286711f54eb549ac6e4c6f375156ab3cf7\", \"blink_heap_unittests\": \"35ba28c13d8ad6d422f21d303a2cbde4667d4236\", \"blink_platform_unittests\": \"573264ec9a5fb05a8260cda9345534b620c3a12b\", \"blink_unittests\": \"c7ce1ca135d693bb10050950ab71e57bd98cdade\", \"boringssl_crypto_tests\": \"2a744a803e84fbbe963af661e87ec3fc47d43846\", \"boringssl_ssl_tests\": \"15c0f261e75eaec3f84fa782e8d27b6c5ef5c279\", \"browser_switcher_bho_unittests\": \"6172f3c73c09ff4122d690f9c7be58fe8094035f\", \"browser_tests\": \"5115ca308ed94f3599ca6e8ca8ae928fee7978f8\", \"capture_unittests\": \"006070cec308bac5c128772f0753437e828612b4\", \"cast_unittests\": \"a5ef8f7d3b9390b885b4ccc2b70d6b91cb9c5188\", \"cc_unittests\": \"f5a8e0db2aaf297034e8a2401ad8bfd6e96eccfb\", \"chrome_app_unittests\": \"7378f99113608cd774612770a691bb80901b0aec\", \"chrome_cleaner_unittests\": \"f122f98647962f7df6e5ecc676ca71529b83de7d\", \"chrome_elf_unittests\": \"1f9550961413148ac429c5e58150524dfe74508e\", \"chromedriver_unittests\": \"17aa5909b21a8651056ac1eeaa233ec1a2e94087\", \"components_browsertests\": \"bfec06e20958a32997b32332a8004804e8554822\", \"components_unittests\": \"1483ad918cd9f0f149b956bc761d470706781c5f\", \"compositor_unittests\": \"8b14a66d3e37df550030d559181980f804929ebe\", \"content_browsertests\": \"890c3abf9bdcdc702ef09f651b8fa0adb8d2b926\", \"content_unittests\": \"d8320ce5635b93ebc8f9113073a6a7a86305b004\", \"courgette_unittests\": \"adde1bbd65e40f790cd72109145b121fa7edaeba\", \"crashpad_tests\": \"09a29c4885f59e988b50557bf40928bb964465b8\", \"cronet_tests\": \"770be48acc4791bcf7b7003eb5d73a275994e20e\", \"cronet_unittests\": \"59af2b32ae903c4920ebe9c0c46970790eadd981\", \"crypto_unittests\": \"261c7a98f52367684bc9849d31702436c1cd9b1f\", \"delayloads_unittests\": \"35915964d7dc12fc34b242f804567742a741babb\", \"device_unittests\": \"4f82b6a2ecf097d6b704328de634dfb94185e2ff\", \"display_unittests\": \"33184b992a0793dd2049967f083e0b3c9945c1e0\", \"elevation_service_unittests\": \"21facd8a2aad748e0fd196338de150220f45652d\", \"events_unittests\": \"0e4959b1e0d9ac593c7e98a7aac5d28f45fc0cca\", \"extensions_browsertests\": \"c0920f40d06041a25a3446f86d65ac0e0c2e159b\", \"extensions_unittests\": \"cb89927ab2af6ad5fe074a84a498d048daebc929\", \"filesystem_service_unittests\": \"c4a5cd888f1e7b4c3f6f8031b2fb89383151df4f\", \"gcm_unit_tests\": \"6247b984f104b671261abccaf0646213f32726cf\", \"gcp_unittests\": \"c1508a57fed6c23cea4ebea2b8103a41614617f3\", \"gfx_unittests\": \"4c3ab02bc633d9371daf111c0b01a87c066a2862\", \"gin_unittests\": \"8135f2c3a5cc5056f07c8eb73cdd09a12b80f303\", \"google_apis_unittests\": \"e60dcec51527513a71ba923b2be5ef4b55331bac\", \"gpu_unittests\": \"cad98f4b2b9f0aa8f5b8772826ab9627f1f9a460\", \"gwp_asan_unittests\": \"7482c7b5419e2cf5c8d3e82a692028641595f68f\", \"headless_browsertests\": \"97ad200cdd2095a38b9c5f3e914054e40e69bbcf\", \"headless_unittests\": \"60e13fa29ec04bd1bfb6ceb00cb7e00e8bff6f8a\", \"install_static_unittests\": \"303a0d902c9f62bfdb1debdfe8a4394ed1080e0e\", \"installer_util_unittests\": \"8c9ca46ff4bd0b787752af5f268fe94993421bcb\", \"interactive_ui_tests\": \"d9294912425f00cf9eea76cff501e6d51a821bce\", \"ipc_tests\": \"93631baba2343cf486a0b40db690d936fbc2837a\", \"jingle_unittests\": \"02c29ec20b1d88dbb5335bf9bf8d8dc4927f6ee6\", \"latency_unittests\": \"2e8f03ef406c67abae52fd04e7feed9528afc741\", \"libjingle_xmpp_unittests\": \"08e3c9ffaa8a0b82bae92829bf13db25a945a7ef\", \"liburlpattern_unittests\": \"1bc7c1f7c9554e4635a3777750c011ece6f332e7\", \"media_blink_unittests\": \"e193e455126da96aba92cd571e8b769ec1c6d614\", \"media_unittests\": \"da0272d94c5083e36078087dfb6f2f934e22c41d\", \"message_center_unittests\": \"954c1033e2e695ce963f1d83138d3243744b3ac9\", \"midi_unittests\": \"5d65e66ac0f6b2deb77054dffb29510356cf1b2e\", \"mojo_core_unittests\": \"1f09e40b3f10dda5e1e569c18ee1cfda75bff3af\", \"mojo_unittests\": \"50474a1ab152bf0ec39c6d76025198df80df48d0\", \"nacl_loader_unittests\": \"ee4270e56cad947e5e5de6239085d735b5d3b091\", \"native_theme_unittests\": \"77cadc7fbd5e678c082b64656b7631c708bbcd90\", \"net_unittests\": \"3ebddc1d056abdc819cf100c1fc3af50a3ba57fb\", \"notification_helper_unittests\": \"b258f012e00414a0ada1c881245cce75ae863c05\", \"pdf_unittests\": \"ca4237bfa4c63197e60eaa35344e43a4d85473a4\", \"perfetto_unittests\": \"101a64de820530ab7b6a96360210d23efcbc4e1e\", \"ppapi_unittests\": \"055b15661260dd1c2b62e0db8c3bf1890ae36645\", \"printing_unittests\": \"83c42f23cf01ce2e8957c790f7d273dbe909faf9\", \"remoting_unittests\": \"e080ea6bdb2f142b3fd6a7d81eac8716aac0469b\", \"sbox_integration_tests\": \"a055120c987acc9ede2982c93cdf9003d35bb61e\", \"sbox_unittests\": \"50183fbfa2e1238e33e9c0fa4e90178ceea32658\", \"sbox_validation_tests\": \"3a420e4a13a5f1051577a1938f7205b0c6532736\", \"service_manager_unittests\": \"e676bf725998d0f92b4480e2c55c6fb75ff5c584\", \"services_unittests\": \"bbb8b5d186c9cf3dd44144132ef4739711fedafb\", \"setup_unittests\": \"aeca65d6478b52ff1b3d96880b77bb15c242f7c2\", \"shell_dialogs_unittests\": \"83d0e837d0c60e783a13d6be60742276a03fd417\", \"skia_unittests\": \"573ea6e5853e469c66304bdd4b0a856a09f63c28\", \"snapshot_unittests\": \"3cbddd76d0eebb0a4a12fb353bbaace5723ba947\", \"sql_unittests\": \"f61dd24b1755390b5a226f8f020139b249134db8\", \"storage_unittests\": \"0ef12104b37bfa9cf5d0dbba6d767f75122e0e38\", \"sync_integration_tests\": \"e048df87a0278a8cd5bce40f2697bf7158afcf85\", \"traffic_annotation_auditor_unittests\": \"a7910e492d3052ca33a0a85189aaf1a008da4cc9\", \"ui_base_unittests\": \"0c4f17f0c252be959d226641e14a76d8514f7f38\", \"ui_touch_selection_unittests\": \"cfded48b3f179cb2bd84cf631044a0dec8a0f203\", \"unit_tests\": \"8b947ba8651e33c6778d5ebee5be8b53bee2f282\", \"updater_tests\": \"636c95cd2725c16692b26ac4e6fd1ea1ae70aa5b\", \"url_unittests\": \"faa0692ff927bd734dba30b6e7405bbeadf4ffe8\", \"views_unittests\": \"69935e5785c4bd0581fd709a73921abf69a63b88\", \"viz_unittests\": \"ed9f2a5833d54ae03d5273b01b261c86a911dc7c\", \"vr_common_unittests\": \"16d068d612b64627e1c8d9fa69ebfa14b8bc17f9\", \"vr_pixeltests\": \"f6ef974923eca188b0f1bf38fc1aceff4b825f2b\", \"weblayer_browsertests\": \"cf0d59825be78eff2bbbf9468f6371098734311d\", \"weblayer_unittests\": \"3f07db639981245f01b5afc4bccb9f517ca3b3b0\", \"wm_unittests\": \"eef3c2c8dba778f5193c53240efafc94b18d5939\", \"wtf_unittests\": \"004d9b8d2bbb51131f0a61657b4d792b4a231af3\", \"zlib_unittests\": \"a290191908b70a51a954f7730ea204b1f3a4d2b3\", \"zucchini_unittests\": \"ce909570eb5e8d7c492cabb979d8762a76732a27\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"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-juf7\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609959355081903",
"status": "COMPLETED",
"status_changed_ts": "1609962942490690",
"tags": [
"build_address:luci.chromium.ci/win-asan/16658",
"builder:win-asan",
"buildset:commit/git/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb3c1fae634c7db0176b70ed50a5964cd39d67d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024259040623616016",
"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/8858828978606906816/+/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:50f1dfb430b52710",
"user_agent:luci-scheduler"
],
"updated_ts": "1609962942510153",
"url": "https://ci.chromium.org/b/8858828978606906816",
"utcnow_ts": "1609962944397313"
}
}
[19:55:44.421] Invocation finished in 59m53.852458419s with status SUCCEEDED