[16:56:46.352] New invocation is queued and will start shortly
[16:56:47.391] Starting the invocation (attempt 1)
[16:56:47.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:56:47.427] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:56:47.427] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023541756163436608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"451706e846cac780e8360f8a4dc3c591a4b98a0b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023541756163436608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"gitiles_ref:refs/heads/master"
]
}
[16:56:48.696] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610643407520732",
"id": "8858111694274429568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"451706e846cac780e8360f8a4dc3c591a4b98a0b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610643408391335",
"tags": [
"build_address:luci.chromium.ci/win-asan/16820",
"builder:win-asan",
"buildset:commit/git/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023541756163436608",
"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/8858111694274429568/+/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": "1610643408391471",
"url": "https://ci.chromium.org/b/8858111694274429568",
"utcnow_ts": "1610643408643110"
}
}
[16:56:48.696] Task URL: https://ci.chromium.org/b/8858111694274429568
[16:56:48.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:2:0) after 1m0s
[16:56:52.271] Received PubSub notification, asking Buildbucket for the build status
[16:56:52.335] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:48.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:2:0)
[16:57:48.716] Timer tick, asking Buildbucket for the build status
[16:57:48.830] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:48.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:4:0) after 1m0s
[16:58:48.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:4:0)
[16:58:48.902] Timer tick, asking Buildbucket for the build status
[16:58:49.046] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:49.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:5:0) after 1m0s
[16:59:49.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:5:0)
[16:59:49.025] Timer tick, asking Buildbucket for the build status
[16:59:49.398] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:49.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:6:0) after 1m0s
[17:00:49.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:6:0)
[17:00:49.429] Timer tick, asking Buildbucket for the build status
[17:00:49.602] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:49.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:7:0) after 1m0s
[17:01:49.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:7:0)
[17:01:49.790] Timer tick, asking Buildbucket for the build status
[17:01:49.946] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:49.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:8:0) after 1m0s
[17:02:50.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:8:0)
[17:02:50.033] Timer tick, asking Buildbucket for the build status
[17:02:50.538] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:50.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:9:0) after 1m0s
[17:03:50.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:9:0)
[17:03:50.557] Timer tick, asking Buildbucket for the build status
[17:03:50.840] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:50.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:10:0) after 1m0s
[17:04:50.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:10:0)
[17:04:50.952] Timer tick, asking Buildbucket for the build status
[17:04:51.062] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:51.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:11:0) after 1m0s
[17:05:51.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:11:0)
[17:05:51.136] Timer tick, asking Buildbucket for the build status
[17:05:51.243] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:51.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:12:0) after 1m0s
[17:06:51.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:12:0)
[17:06:51.339] Timer tick, asking Buildbucket for the build status
[17:06:51.512] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:51.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:13:0) after 1m0s
[17:07:51.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:13:0)
[17:07:51.576] Timer tick, asking Buildbucket for the build status
[17:07:51.867] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:51.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:14:0) after 1m0s
[17:08:51.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:14:0)
[17:08:51.891] Timer tick, asking Buildbucket for the build status
[17:08:52.101] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:52.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:15:0) after 1m0s
[17:09:52.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:15:0)
[17:09:52.186] Timer tick, asking Buildbucket for the build status
[17:09:52.269] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:52.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:16:0) after 1m0s
[17:10:52.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:16:0)
[17:10:52.323] Timer tick, asking Buildbucket for the build status
[17:10:52.444] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:52.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:17:0) after 1m0s
[17:11:52.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:17:0)
[17:11:52.485] Timer tick, asking Buildbucket for the build status
[17:11:52.700] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:52.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:18:0) after 1m0s
[17:12:52.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:18:0)
[17:12:52.722] Timer tick, asking Buildbucket for the build status
[17:12:53.794] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:53.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:19:0) after 1m0s
[17:13:53.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:19:0)
[17:13:53.816] Timer tick, asking Buildbucket for the build status
[17:13:53.930] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:53.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:20:0) after 1m0s
[17:14:53.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:20:0)
[17:14:53.969] Timer tick, asking Buildbucket for the build status
[17:14:54.306] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:54.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:21:0) after 1m0s
[17:15:54.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:21:0)
[17:15:54.338] Timer tick, asking Buildbucket for the build status
[17:15:54.431] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:54.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:22:0) after 1m0s
[17:16:54.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:22:0)
[17:16:54.435] Timer tick, asking Buildbucket for the build status
[17:16:54.567] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:54.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:23:0) after 1m0s
[17:17:54.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:23:0)
[17:17:54.656] Timer tick, asking Buildbucket for the build status
[17:17:54.855] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:24:0) after 1m0s
[17:18:54.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:24:0)
[17:18:54.899] Timer tick, asking Buildbucket for the build status
[17:18:55.298] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:55.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:25:0) after 1m0s
[17:19:55.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:25:0)
[17:19:55.294] Timer tick, asking Buildbucket for the build status
[17:19:55.605] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:55.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:26:0) after 1m0s
[17:20:55.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:26:0)
[17:20:55.651] Timer tick, asking Buildbucket for the build status
[17:20:55.755] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:55.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:27:0) after 1m0s
[17:21:55.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:27:0)
[17:21:55.839] Timer tick, asking Buildbucket for the build status
[17:21:55.976] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:55.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:28:0) after 1m0s
[17:22:56.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:28:0)
[17:22:56.039] Timer tick, asking Buildbucket for the build status
[17:22:56.173] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:56.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:29:0) after 1m0s
[17:23:56.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:29:0)
[17:23:56.252] Timer tick, asking Buildbucket for the build status
[17:23:56.830] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:56.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:30:0) after 1m0s
[17:24:56.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:30:0)
[17:24:56.911] Timer tick, asking Buildbucket for the build status
[17:24:57.304] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:31:0) after 1m0s
[17:25:57.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:31:0)
[17:25:57.314] Timer tick, asking Buildbucket for the build status
[17:25:57.416] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:57.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:32:0) after 1m0s
[17:26:57.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:32:0)
[17:26:57.428] Timer tick, asking Buildbucket for the build status
[17:26:57.553] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:57.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:33:0) after 1m0s
[17:27:57.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:33:0)
[17:27:57.557] Timer tick, asking Buildbucket for the build status
[17:27:57.751] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:57.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:34:0) after 1m0s
[17:28:57.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:34:0)
[17:28:57.759] Timer tick, asking Buildbucket for the build status
[17:28:57.835] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:35:0) after 1m0s
[17:29:57.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:35:0)
[17:29:57.880] Timer tick, asking Buildbucket for the build status
[17:29:57.971] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:57.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:36:0) after 1m0s
[17:30:58.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:36:0)
[17:30:58.005] Timer tick, asking Buildbucket for the build status
[17:30:58.113] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:58.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:37:0) after 1m0s
[17:31:58.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:37:0)
[17:31:58.140] Timer tick, asking Buildbucket for the build status
[17:31:58.884] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:58.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:38:0) after 1m0s
[17:32:58.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:38:0)
[17:32:58.969] Timer tick, asking Buildbucket for the build status
[17:32:59.232] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:59.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:39:0) after 1m0s
[17:33:59.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:39:0)
[17:33:59.300] Timer tick, asking Buildbucket for the build status
[17:33:59.690] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:59.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:40:0) after 1m0s
[17:34:59.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:40:0)
[17:34:59.717] Timer tick, asking Buildbucket for the build status
[17:35:00.823] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:00.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:41:0) after 1m0s
[17:36:01.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:41:0)
[17:36:01.118] Timer tick, asking Buildbucket for the build status
[17:36:01.235] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:01.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:42:0) after 1m0s
[17:37:01.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:42:0)
[17:37:01.644] Timer tick, asking Buildbucket for the build status
[17:37:01.799] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:01.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:43:0) after 1m0s
[17:38:01.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:43:0)
[17:38:01.855] Timer tick, asking Buildbucket for the build status
[17:38:01.985] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:01.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:44:0) after 1m0s
[17:39:02.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:44:0)
[17:39:02.119] Timer tick, asking Buildbucket for the build status
[17:39:02.198] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:02.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:45:0) after 1m0s
[17:40:02.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:45:0)
[17:40:02.212] Timer tick, asking Buildbucket for the build status
[17:40:02.345] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:02.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:46:0) after 1m0s
[17:41:02.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:46:0)
[17:41:02.457] Timer tick, asking Buildbucket for the build status
[17:41:02.616] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:02.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:47:0) after 1m0s
[17:42:02.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:47:0)
[17:42:02.781] Timer tick, asking Buildbucket for the build status
[17:42:03.662] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:03.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:48:0) after 1m0s
[17:43:03.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:48:0)
[17:43:03.701] Timer tick, asking Buildbucket for the build status
[17:43:03.832] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:03.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:49:0) after 1m0s
[17:44:03.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:49:0)
[17:44:03.831] Timer tick, asking Buildbucket for the build status
[17:44:03.980] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:03.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:50:0) after 1m0s
[17:45:04.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:50:0)
[17:45:04.051] Timer tick, asking Buildbucket for the build status
[17:45:05.035] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:05.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:51:0) after 1m0s
[17:46:05.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:51:0)
[17:46:05.172] Timer tick, asking Buildbucket for the build status
[17:46:06.002] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:06.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:52:0) after 1m0s
[17:47:06.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:52:0)
[17:47:06.066] Timer tick, asking Buildbucket for the build status
[17:47:06.554] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:06.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:53:0) after 1m0s
[17:48:06.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:53:0)
[17:48:06.602] Timer tick, asking Buildbucket for the build status
[17:48:06.879] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:06.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:54:0) after 1m0s
[17:49:06.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:54:0)
[17:49:06.909] Timer tick, asking Buildbucket for the build status
[17:49:06.981] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:55:0) after 1m0s
[17:50:06.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:55:0)
[17:50:06.987] Timer tick, asking Buildbucket for the build status
[17:50:08.683] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:08.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:56:0) after 1m0s
[17:51:08.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:56:0)
[17:51:08.690] Timer tick, asking Buildbucket for the build status
[17:51:08.760] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:08.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:57:0) after 1m0s
[17:52:08.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:57:0)
[17:52:08.777] Timer tick, asking Buildbucket for the build status
[17:52:08.899] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:08.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:58:0) after 1m0s
[17:53:08.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:58:0)
[17:53:08.970] Timer tick, asking Buildbucket for the build status
[17:53:09.404] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:09.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:59:0) after 1m0s
[17:54:09.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:59:0)
[17:54:09.517] Timer tick, asking Buildbucket for the build status
[17:54:09.609] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:09.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:60:0) after 1m0s
[17:55:09.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:60:0)
[17:55:09.641] Timer tick, asking Buildbucket for the build status
[17:55:09.732] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:09.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:61:0) after 1m0s
[17:56:09.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:61:0)
[17:56:09.807] Timer tick, asking Buildbucket for the build status
[17:56:10.683] Build 8858111694274429568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:10.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023541756163436608:62:0) after 1m0s
[17:57:06.434] Received PubSub notification, asking Buildbucket for the build status
[17:57:06.495] Build 8858111694274429568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:57:06.495] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610647024584668",
"created_by": "project:chromium",
"created_ts": "1610643407520732",
"id": "8858111694274429568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"451706e846cac780e8360f8a4dc3c591a4b98a0b\"}}",
"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\": \"451706e846cac780e8360f8a4dc3c591a4b98a0b\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16820, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"72c58a865b57e4a81334d82b1b7af302b5b579d9\", \"got_dawn_revision\": \"247f511f198f901ce3eb0344f355f53bf9ffe59d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"451706e846cac780e8360f8a4dc3c591a4b98a0b\", \"got_revision_cp\": \"refs/heads/master@{#843578}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"c80a50a3c4de747d61e044cea6f94223016110d2\", \"got_v8_revision_cp\": \"refs/heads/8.9.273@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"451706e846cac780e8360f8a4dc3c591a4b98a0b\", \"swarm_hashes_refs/heads/master(at){#843578}_without_patch\": {\"absl_hardening_tests\": \"878726de3a38e523b4a2859124b222fcc23f712c\", \"accessibility_unittests\": \"b4d54b581c323e97c439a50e770154062a2a95f2\", \"angle_unittests\": \"3c875bbc1c172d5c96e54b0677e49cf709c8b2a9\", \"app_shell_unittests\": \"e23ad488d5d808f3a73d4f60e4fa8fc8c7980c22\", \"aura_unittests\": \"4d53498f88fb6c81c506a61aca6ee07ff136054b\", \"base_unittests\": \"6ebb2e8240adfb70971ede8e2ad13ab6ea35d648\", \"base_util_unittests\": \"cfdd231738519a0317de3059f699a206e878f707\", \"blink_common_unittests\": \"ef115d1d3efe033aa27b3cf9d29de0c472c7db69\", \"blink_fuzzer_unittests\": \"36d4db135a4d92186ed239a81b5878003846448b\", \"blink_heap_unittests\": \"7bfeb6fd34654e9163eaa8c1486b6c32b8419a24\", \"blink_platform_unittests\": \"7df5b5db224a90cdf2fd247f6a898420d34bed89\", \"blink_unittests\": \"482b0f729627a0c2f85ca9abb58a10c6ec251d67\", \"boringssl_crypto_tests\": \"1ce9924c87e7f53eb3a8b7e58911ccec4d29e1f2\", \"boringssl_ssl_tests\": \"666d7af6e628da6bd36deb18f6f2177194ec4c17\", \"browser_switcher_bho_unittests\": \"7d3c8b3b0bbf65ebf8a985e4fb879f01a88d32f1\", \"browser_tests\": \"9b090581f2fed202c0dc765596b48cc6cd0c262a\", \"capture_unittests\": \"ccad5062418fef4ec5070533dec90dc951bb7a60\", \"cast_unittests\": \"52177898495f58ac7af07f4744f9f09a9a6b9158\", \"cc_unittests\": \"4f7001122d746daecf373a6edcaab484cfb5ab48\", \"chrome_app_unittests\": \"d96e4acebe3d812927a7957e7f76d87569cff096\", \"chrome_cleaner_unittests\": \"6a936c58727532b057ae1a6f16c6f2f62adb7d57\", \"chrome_elf_unittests\": \"014491b87a8a39e5faedae567d4f7de79c6f3ebb\", \"chromedriver_unittests\": \"696a8d24b2bdca00286766ea8361d6b18eb31e15\", \"components_browsertests\": \"a9c938cffcb0997034005a8f48812eced338d3ef\", \"components_unittests\": \"fd8ac0dd10e067c17ca6601a54c653d638d9cd79\", \"compositor_unittests\": \"9b7d62909a28ba24990e709eb2935dde7c816526\", \"content_browsertests\": \"2df391134f18ff59a7dacb312e2e86bb306fbda6\", \"content_unittests\": \"55393aa9d2f2b2dff00c0f386688b9ff8d2a4c11\", \"courgette_unittests\": \"adbed46353393efef38bfc1187892114d362066c\", \"crashpad_tests\": \"fb1ab75a816ae95b4efe99ab8a42853b8e28dd0d\", \"cronet_tests\": \"4a9ab00a1b4b9fc9579a02606cae7f45710bd5ee\", \"cronet_unittests\": \"545e1b43d66b888d9366d9eef68671cb14138d86\", \"crypto_unittests\": \"83f1b023423ef3708e1255086bdaa1dc988ff0dc\", \"delayloads_unittests\": \"029c9163e9cb96e57bb911896085189bc579f3c4\", \"device_unittests\": \"fc719f11e7ede68143ed96d4bb163c09b75aa0bd\", \"display_unittests\": \"2a6fb9136e9abf2933c73830aec08b29e570a384\", \"elevation_service_unittests\": \"059d81c2cde5ebac671b3b5ed8b66ed83978ab02\", \"events_unittests\": \"e361f5fcc1be04aad82728560b0f424191e38008\", \"extensions_browsertests\": \"7b5a5bd51145a478bcfd6eca8da93fa548c666e4\", \"extensions_unittests\": \"8ca4d5429cdf4aae000ca507cf5e69c59000268f\", \"filesystem_service_unittests\": \"39932b1a4503a716eebf53d5cc545a417c34b466\", \"gcm_unit_tests\": \"147a50a5a89fa0f24502238599f69be624df13eb\", \"gcp_unittests\": \"b391d3f71b563c7f09e5adf65202e983856bc5c0\", \"gfx_unittests\": \"15bc90a5ac0525beabdfb0c09e8c854db1dca8d9\", \"gin_unittests\": \"a253075aa18af89fd219591e16ba36e0fde61574\", \"google_apis_unittests\": \"f20132d7b059cc3edcb1f8235e1413b7ae760057\", \"gpu_unittests\": \"4f1055746c747d61b27a1a8e2d64059491b72d83\", \"gwp_asan_unittests\": \"b7f24df5e0937ed612b0b65172c570f5b78464e9\", \"headless_browsertests\": \"1b8cd119ccba9677917d05b305dafcaf043fb572\", \"headless_unittests\": \"cbec6ace89c8dc67692a2dc48fcdf148b61f7e96\", \"install_static_unittests\": \"91bd99cbd3cdbc7df319486101f0c6b8c04173a7\", \"installer_util_unittests\": \"8f2b3aa2342f516f52418977934254cb9cc22572\", \"interactive_ui_tests\": \"e072145d29286220b3e515740c394ad4e5049a96\", \"ipc_tests\": \"c2d032ee1914afba83d92a3895ca84a56da388f7\", \"jingle_unittests\": \"4108f3f2aae526d098b7abcdb463309ae708f070\", \"latency_unittests\": \"6c5a4c6c0706f5648d40feeccb9e640dc15f36e9\", \"libjingle_xmpp_unittests\": \"1bef5e5ad7cf1327d5fae364a41763f7197bb19d\", \"liburlpattern_unittests\": \"a456cd48ccc7660eb6204c5985d74ab7d8522fa5\", \"media_blink_unittests\": \"29fb62c60c920c595b7f590f17c30f168dcfb850\", \"media_unittests\": \"ba33c1beb495569db484da751f5d2e53d4682647\", \"message_center_unittests\": \"a632668d4f8e9073fefab31a83f848db0eeea962\", \"midi_unittests\": \"cc5122933df17732f8733d997994fd13f2736157\", \"mojo_core_unittests\": \"3d4258c5cd6cfe7d2eb16701dc196582fb9703bc\", \"mojo_unittests\": \"732c7ba00d854874266366308e68e48622772f04\", \"nacl_loader_unittests\": \"393d3493a9f59764b29491fbc367806ffc10bf2d\", \"native_theme_unittests\": \"06e1306b52113b1b9d985e203ec17988f25e2da5\", \"net_unittests\": \"ae78a92c63bce5eb90ffc6c252dc0d86a3bfbb91\", \"notification_helper_unittests\": \"04172e8be88dc6658fa0368a3ed87a73cf28eda2\", \"pdf_unittests\": \"283226029be2c79723f9eea557497727e5a0b18c\", \"perfetto_unittests\": \"aab3c745c4a9adeb4c712da5f1b636f29b2a8e1e\", \"ppapi_unittests\": \"549f9021d0b28f577e6ce8c8c0a2eaeea16f0c04\", \"printing_unittests\": \"89a1eab6036e37ae30ac9318bc6ee635c32bb9cf\", \"remoting_unittests\": \"fa9f9976e54234082a0215bab19e7dd613c22a81\", \"sbox_integration_tests\": \"964b4114644df200659a3c927e1ea9e45491420a\", \"sbox_unittests\": \"7edfd1a87755167619c40d254e413e73d0757303\", \"sbox_validation_tests\": \"d276ca38d805ecb4d3413d2aed7e3bd86262079b\", \"service_manager_unittests\": \"9b7939926bcf87e6e68d35a0e59ef98fde055322\", \"services_unittests\": \"03f1579c5120fe4cc4f1cdbac6214282854b31b6\", \"setup_unittests\": \"27b990c56ac234fc6580b763594a9b9b5dde0d39\", \"shell_dialogs_unittests\": \"a80ad7ce9ef89dfcdabbbed14a15ccdb0fb600e9\", \"skia_unittests\": \"3a4dff1e0b6276182cbee53f6b7e17059dd63a0d\", \"snapshot_unittests\": \"4be7f99554b1c4e728952860a8c711a95bbaf57d\", \"sql_unittests\": \"5ea19884c5543828d91398189703a37a77e4e42c\", \"storage_unittests\": \"4a065a4ea4d93ecce0e0c64c1907dd8f56e0145f\", \"sync_integration_tests\": \"afd7f11ac8cfee448f18608e9e8d6ae57e0b3051\", \"traffic_annotation_auditor_unittests\": \"57f4a0cf8794bc13d9f1287bdcbada7a7edcc5bf\", \"ui_base_unittests\": \"9242afbc97021272e0cf2bd7397099cf7ef7ae17\", \"ui_touch_selection_unittests\": \"db96886ece9545e65b42522cd131f4e0738b6b16\", \"unit_tests\": \"6e3c9cc40ea0cb0d9996501bfc35f72589093c2e\", \"updater_tests\": \"55eb3565643f609e8731be0c4fef75e21ae52438\", \"url_unittests\": \"a7de39bc5ac0969a15587f52e6210ec13341df4d\", \"views_unittests\": \"3f6fac7ba333b5bee34aec3862739f8e7890cd34\", \"viz_unittests\": \"7049832f72b872534b104c5d87eed53a73b28f1b\", \"vr_common_unittests\": \"c72122624d883187ea73ee6fb70ec7b186b49f8a\", \"vr_pixeltests\": \"ca5859af928b4d0cc2152416f523993619b1452f\", \"weblayer_browsertests\": \"4b89407671a9069a42512b8f992dd1b78f17bb81\", \"weblayer_unittests\": \"164b4aae358ca30edf3f09e605d04015c12cde07\", \"wm_unittests\": \"8efeaea0b859e1846f1fa8520c44ce5f471954de\", \"wtf_unittests\": \"b1991c55dd81cf3b9b092a01419f9b18f7a54fee\", \"zlib_unittests\": \"74244047cdf9654db7403d9890caed323b846778\", \"zucchini_unittests\": \"0e026611e9f3bca329ad439b3813028ae63f631d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610643411416365",
"status": "COMPLETED",
"status_changed_ts": "1610647025189451",
"tags": [
"build_address:luci.chromium.ci/win-asan/16820",
"builder:win-asan",
"buildset:commit/git/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/451706e846cac780e8360f8a4dc3c591a4b98a0b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023541756163436608",
"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/8858111694274429568/+/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:511aa59346f5e810",
"user_agent:luci-scheduler"
],
"updated_ts": "1610647025210932",
"url": "https://ci.chromium.org/b/8858111694274429568",
"utcnow_ts": "1610647026486808"
}
}
[17:57:06.495] Invocation finished in 1h0m20.159545226s with status SUCCEEDED