[16:57:38.885] New invocation is queued and will start shortly
[16:57:40.384] Starting the invocation (attempt 1)
[16:57:40.422] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:57:40.423] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:57:40.423] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024900655576790960",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024900655576790960",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"gitiles_ref:refs/heads/master"
]
}
[16:57:41.524] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609347460517748",
"id": "8859470593198709328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609347461178712",
"tags": [
"build_address:luci.chromium.ci/win-asan/16557",
"builder:win-asan",
"buildset:commit/git/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024900655576790960",
"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/8859470593198709328/+/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": "1609347461178878",
"url": "https://ci.chromium.org/b/8859470593198709328",
"utcnow_ts": "1609347461496048"
}
}
[16:57:41.524] Task URL: https://ci.chromium.org/b/8859470593198709328
[16:57:41.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:2:0) after 1m0s
[16:57:42.727] Received PubSub notification, asking Buildbucket for the build status
[16:57:42.804] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:41.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:2:0)
[16:58:41.523] Timer tick, asking Buildbucket for the build status
[16:58:42.144] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:42.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:4:0) after 1m0s
[16:59:42.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:4:0)
[16:59:42.197] Timer tick, asking Buildbucket for the build status
[16:59:42.313] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:42.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:5:0) after 1m0s
[17:00:42.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:5:0)
[17:00:42.317] Timer tick, asking Buildbucket for the build status
[17:00:42.763] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:42.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:6:0) after 1m0s
[17:01:42.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:6:0)
[17:01:42.870] Timer tick, asking Buildbucket for the build status
[17:01:44.215] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:44.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:7:0) after 1m0s
[17:02:44.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:7:0)
[17:02:44.236] Timer tick, asking Buildbucket for the build status
[17:02:44.510] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:44.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:8:0) after 1m0s
[17:03:44.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:8:0)
[17:03:44.597] Timer tick, asking Buildbucket for the build status
[17:03:44.671] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:44.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:9:0) after 1m0s
[17:04:44.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:9:0)
[17:04:44.724] Timer tick, asking Buildbucket for the build status
[17:04:44.781] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:44.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:10:0) after 1m0s
[17:05:45.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:10:0)
[17:05:45.196] Timer tick, asking Buildbucket for the build status
[17:05:45.299] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:45.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:11:0) after 1m0s
[17:06:45.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:11:0)
[17:06:45.568] Timer tick, asking Buildbucket for the build status
[17:06:45.619] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:45.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:12:0) after 1m0s
[17:07:46.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:12:0)
[17:07:46.077] Timer tick, asking Buildbucket for the build status
[17:07:46.317] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:46.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:13:0) after 1m0s
[17:08:46.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:13:0)
[17:08:46.367] Timer tick, asking Buildbucket for the build status
[17:08:46.486] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:46.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:14:0) after 1m0s
[17:09:46.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:14:0)
[17:09:46.917] Timer tick, asking Buildbucket for the build status
[17:09:47.262] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:47.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:15:0) after 1m0s
[17:10:47.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:15:0)
[17:10:47.279] Timer tick, asking Buildbucket for the build status
[17:10:47.433] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:47.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:16:0) after 1m0s
[17:11:47.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:16:0)
[17:11:47.552] Timer tick, asking Buildbucket for the build status
[17:11:47.628] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:47.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:17:0) after 1m0s
[17:12:47.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:17:0)
[17:12:47.751] Timer tick, asking Buildbucket for the build status
[17:12:48.126] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:48.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:18:0) after 1m0s
[17:13:48.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:18:0)
[17:13:48.148] Timer tick, asking Buildbucket for the build status
[17:13:48.277] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:48.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:19:0) after 1m0s
[17:14:48.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:19:0)
[17:14:48.436] Timer tick, asking Buildbucket for the build status
[17:14:48.581] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:48.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:20:0) after 1m0s
[17:15:48.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:20:0)
[17:15:48.664] Timer tick, asking Buildbucket for the build status
[17:15:48.792] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:48.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:21:0) after 1m0s
[17:16:48.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:21:0)
[17:16:48.896] Timer tick, asking Buildbucket for the build status
[17:16:49.038] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:49.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:22:0) after 1m0s
[17:17:49.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:22:0)
[17:17:49.172] Timer tick, asking Buildbucket for the build status
[17:17:49.901] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:49.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:23:0) after 1m0s
[17:18:50.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:23:0)
[17:18:50.090] Timer tick, asking Buildbucket for the build status
[17:18:50.237] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:50.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:24:0) after 1m0s
[17:19:50.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:24:0)
[17:19:50.264] Timer tick, asking Buildbucket for the build status
[17:19:50.435] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:50.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:25:0) after 1m0s
[17:20:50.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:25:0)
[17:20:50.436] Timer tick, asking Buildbucket for the build status
[17:20:50.554] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:50.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:26:0) after 1m0s
[17:21:50.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:26:0)
[17:21:50.682] Timer tick, asking Buildbucket for the build status
[17:21:50.822] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:50.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:27:0) after 1m0s
[17:22:50.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:27:0)
[17:22:50.888] Timer tick, asking Buildbucket for the build status
[17:22:50.989] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:50.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:28:0) after 1m0s
[17:23:51.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:28:0)
[17:23:51.320] Timer tick, asking Buildbucket for the build status
[17:23:51.471] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:51.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:29:0) after 1m0s
[17:24:51.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:29:0)
[17:24:51.654] Timer tick, asking Buildbucket for the build status
[17:24:51.773] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:51.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:30:0) after 1m0s
[17:25:51.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:30:0)
[17:25:51.858] Timer tick, asking Buildbucket for the build status
[17:25:52.024] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:52.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:31:0) after 1m0s
[17:26:52.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:31:0)
[17:26:52.102] Timer tick, asking Buildbucket for the build status
[17:26:52.184] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:52.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:32:0) after 1m0s
[17:27:52.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:32:0)
[17:27:52.209] Timer tick, asking Buildbucket for the build status
[17:27:52.635] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:52.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:33:0) after 1m0s
[17:28:52.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:33:0)
[17:28:52.818] Timer tick, asking Buildbucket for the build status
[17:28:52.900] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:52.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:34:0) after 1m0s
[17:29:53.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:34:0)
[17:29:53.005] Timer tick, asking Buildbucket for the build status
[17:29:53.119] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:53.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:35:0) after 1m0s
[17:30:53.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:35:0)
[17:30:53.239] Timer tick, asking Buildbucket for the build status
[17:30:53.568] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:53.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:36:0) after 1m0s
[17:31:53.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:36:0)
[17:31:53.674] Timer tick, asking Buildbucket for the build status
[17:31:53.750] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:53.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:37:0) after 1m0s
[17:32:53.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:37:0)
[17:32:53.799] Timer tick, asking Buildbucket for the build status
[17:32:53.992] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:53.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:38:0) after 1m0s
[17:33:54.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:38:0)
[17:33:54.044] Timer tick, asking Buildbucket for the build status
[17:33:54.172] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:54.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:39:0) after 1m0s
[17:34:54.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:39:0)
[17:34:54.191] Timer tick, asking Buildbucket for the build status
[17:34:54.252] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:54.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:40:0) after 1m0s
[17:35:54.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:40:0)
[17:35:54.403] Timer tick, asking Buildbucket for the build status
[17:35:54.490] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:54.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:41:0) after 1m0s
[17:36:54.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:41:0)
[17:36:54.578] Timer tick, asking Buildbucket for the build status
[17:36:54.756] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:54.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:42:0) after 1m0s
[17:37:54.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:42:0)
[17:37:54.772] Timer tick, asking Buildbucket for the build status
[17:37:54.905] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:54.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:43:0) after 1m0s
[17:38:55.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:43:0)
[17:38:55.008] Timer tick, asking Buildbucket for the build status
[17:38:55.294] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:55.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:44:0) after 1m0s
[17:39:55.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:44:0)
[17:39:55.338] Timer tick, asking Buildbucket for the build status
[17:39:55.429] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:55.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:45:0) after 1m0s
[17:40:55.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:45:0)
[17:40:55.450] Timer tick, asking Buildbucket for the build status
[17:40:55.567] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:55.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:46:0) after 1m0s
[17:41:55.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:46:0)
[17:41:55.616] Timer tick, asking Buildbucket for the build status
[17:41:56.140] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:56.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:47:0) after 1m0s
[17:42:56.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:47:0)
[17:42:56.144] Timer tick, asking Buildbucket for the build status
[17:42:56.280] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:56.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:48:0) after 1m0s
[17:43:56.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:48:0)
[17:43:56.298] Timer tick, asking Buildbucket for the build status
[17:43:56.453] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:56.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:49:0) after 1m0s
[17:44:56.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:49:0)
[17:44:56.592] Timer tick, asking Buildbucket for the build status
[17:44:56.899] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:56.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:50:0) after 1m0s
[17:45:56.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:50:0)
[17:45:56.945] Timer tick, asking Buildbucket for the build status
[17:45:57.161] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:57.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:51:0) after 1m0s
[17:46:57.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:51:0)
[17:46:57.371] Timer tick, asking Buildbucket for the build status
[17:46:57.716] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:57.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:52:0) after 1m0s
[17:47:57.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:52:0)
[17:47:57.769] Timer tick, asking Buildbucket for the build status
[17:47:57.875] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:57.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:53:0) after 1m0s
[17:48:57.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:53:0)
[17:48:57.913] Timer tick, asking Buildbucket for the build status
[17:48:58.044] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:58.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:54:0) after 1m0s
[17:49:58.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:54:0)
[17:49:58.054] Timer tick, asking Buildbucket for the build status
[17:49:58.149] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:58.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:55:0) after 1m0s
[17:50:58.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:55:0)
[17:50:58.226] Timer tick, asking Buildbucket for the build status
[17:50:58.298] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:58.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:56:0) after 1m0s
[17:51:58.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:56:0)
[17:51:58.706] Timer tick, asking Buildbucket for the build status
[17:51:59.079] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:59.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:57:0) after 1m0s
[17:52:59.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:57:0)
[17:52:59.195] Timer tick, asking Buildbucket for the build status
[17:52:59.597] Build 8859470593198709328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:59.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024900655576790960:58:0) after 1m0s
[17:53:06.509] Received PubSub notification, asking Buildbucket for the build status
[17:53:06.630] Build 8859470593198709328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:53:06.630] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609350783831705",
"created_by": "project:chromium",
"created_ts": "1609347460517748",
"id": "8859470593198709328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\"}}",
"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\": \"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16557, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"938399865e720c2e37616cdad64109ef331bb035\", \"got_dawn_revision\": \"9a38e5b60dccb9ca6f596adf91328aca9c17e62f\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\", \"got_revision_cp\": \"refs/heads/master@{#839759}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"ef6c61213b14d3cf31e97bbd314e1dda24794a0d\", \"got_v8_revision_cp\": \"refs/heads/8.9.199@{#1}\", \"got_webrtc_revision\": \"167ecc9bc53f00845c7a9dbbb21624f9dcf6de82\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32889}\", \"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\": \"7fbc2c95ceecb194dcd0814d77cee6b8441cf609\", \"swarm_hashes_refs/heads/master(at){#839759}_without_patch\": {\"absl_hardening_tests\": \"3ca82dbaabba65bac2cba0d03cfaec3dc2c31202\", \"accessibility_unittests\": \"cc11e206845021c6423bfe8daf738f738c9a30e9\", \"angle_unittests\": \"08351dfeba5907fd0c5e33aa7f9e9e8f7ddc7544\", \"app_shell_unittests\": \"3398302a3dcfecd4653e9862baab0407b7eaeeb7\", \"aura_unittests\": \"7e94aa002cb6d034cdf9c6b279d16435f53d5f74\", \"base_unittests\": \"937d73d48e1882e8fabb28cbe73badd20fdc45c6\", \"base_util_unittests\": \"1cdc7016aa67f7db15faf4afc6cd35c76f2d87e3\", \"blink_common_unittests\": \"c5c48f2d32056fa1541a388995b868af02989d7c\", \"blink_fuzzer_unittests\": \"4c779fd465d438884483e3b1490055f738051aef\", \"blink_heap_unittests\": \"93a85c4b520df8e87d5fdea06cc778150dbcba1d\", \"blink_platform_unittests\": \"4e27c5b71808fcba13ed1674a159e90c687e448c\", \"blink_unittests\": \"41fd5c643b929c2e6c2d4d32ab3e7d7f1f21dcd2\", \"boringssl_crypto_tests\": \"7a30a92e8c1600f750e0f0c953f76839ce17c5b6\", \"boringssl_ssl_tests\": \"f39aa66eba77f3141a5a1aae04323814c055ba8f\", \"browser_switcher_bho_unittests\": \"65e5528c655665a92c69e44475d7b8df5be95ccd\", \"browser_tests\": \"21d2fb7fc476cc3ab5198b6b6876fc75ae66c1f3\", \"capture_unittests\": \"d0a64fea4f55b5bdf6c0d4265ea0e444f63cc48c\", \"cast_unittests\": \"a53851711af0d843f7baddb0e42a96b355e7d618\", \"cc_unittests\": \"01f774ce12a9a87f4c27991e7b9a028c1099e6a6\", \"chrome_app_unittests\": \"c48856d29c9f44dcb69ba0af46f890983d372a8c\", \"chrome_cleaner_unittests\": \"fde283f5b14319de03e8ffa92a6d921fb03299e6\", \"chrome_elf_unittests\": \"c6204972c5a9d728a6694743fe4343a7e60b2014\", \"chromedriver_unittests\": \"521dc217d27e92ad04304eb5b7bdade758009820\", \"components_browsertests\": \"8eaba31c23f45c01efb7ff7363f3b654d8562936\", \"components_unittests\": \"e2972851a6470a92043f90f67d055359d8c6c5f3\", \"compositor_unittests\": \"550dffaa83ab4b577a708832b4c8ebf38e659e4b\", \"content_browsertests\": \"579a04b12498d4609b6853124cb85cee562e1a8a\", \"content_unittests\": \"350d87aa6905c2a05a6bac254b820e34a72d8a8e\", \"courgette_unittests\": \"1b51ca14e8a1ce0a89e692766794f1d2b7e11dd5\", \"crashpad_tests\": \"bd8dbd1ed9c418781e470d952c975ebbaa95bcef\", \"cronet_tests\": \"85b2bb84628ddd67716a5f6b8cf9a1dc7566152a\", \"cronet_unittests\": \"d5722268a9d06c3145387d43b84737c31af595f7\", \"crypto_unittests\": \"bfd899cacdc1ba9e35e38a0844284d3dac46f755\", \"delayloads_unittests\": \"80cff2685c8344da6c9fe05e80c8991b424533c7\", \"device_unittests\": \"eff8b32e73d1de8a7c31dfd1527f4b7268b78e6a\", \"display_unittests\": \"b57b968765c400e706bfa629172a954eeb58d1b6\", \"elevation_service_unittests\": \"92b149d783308635809a86d9972dc7e48f448d25\", \"events_unittests\": \"7818c0c37f000603f2cb65864b517fa4584929db\", \"extensions_browsertests\": \"adbd10e1a514698e37addd2ed3be18e8fdbed11a\", \"extensions_unittests\": \"81742d152c2ad3ecf166f715d857b3d4e64e3fe5\", \"filesystem_service_unittests\": \"04193e33bf434a5c9b864fb3f270b516db11427f\", \"gcm_unit_tests\": \"6a17ddaf6b7ec34d7895f82c2a245f01b1a539a1\", \"gcp_unittests\": \"0be933d9bdb518aed17e419755eb17a04c2bd067\", \"gfx_unittests\": \"2f8136657bf9bf86db8b22a72ba06f29c5574c32\", \"gin_unittests\": \"c6b4ea0a8d86759baa22b2e50afe45dfb3352260\", \"google_apis_unittests\": \"fbf8694266ff0626a946c328b404b2dc7bac07d4\", \"gpu_unittests\": \"3966ec88b5f721ca615a80544b3874c2c3ae79b8\", \"gwp_asan_unittests\": \"0bf41fb37aa03105eb69a6661850bdb84796ad61\", \"headless_browsertests\": \"519975147db0ba2391e9894ea88e1fcc838163c1\", \"headless_unittests\": \"214395dcee11581f997555a2c2578293a296ce3a\", \"install_static_unittests\": \"37c9c1e050cebe0124eb8b80d122d3622e5903f3\", \"installer_util_unittests\": \"7355acbcfda377161e9b1fbb2b89f9b124b2cfa8\", \"interactive_ui_tests\": \"2662431cae40e30c0e3bf87d5e3c81aa27586b60\", \"ipc_tests\": \"703e7aad2a1d1d7069bc9a981c770575e887ac5b\", \"jingle_unittests\": \"4214c04862bd6bc0e54d44d78a3d4c2a704bafe8\", \"latency_unittests\": \"eb3ede4cb752a00b6c9861b87112cb29691554b6\", \"libjingle_xmpp_unittests\": \"1f1f22600ec4a8a5fd098bf9e97085d1b972e87e\", \"liburlpattern_unittests\": \"9ef69c8d10127418c5d86c59cc4bfb0079114634\", \"media_blink_unittests\": \"c3f7ab58c9a9af34666dec96130e36671b3b21a9\", \"media_unittests\": \"cabdf188513a77e7c1608d07f8caf8ef9141d8e3\", \"message_center_unittests\": \"3fe778bc7f7671b169d245142fa6b3911374ae38\", \"midi_unittests\": \"1f23186c5023e9318867a5b2c305a85cf10f6a29\", \"mojo_core_unittests\": \"2cdceb1d9035231fa21918ec38a12b347605a8e9\", \"mojo_unittests\": \"6ce1bb82f33e4f3732c437830910cf34ceb5380b\", \"nacl_loader_unittests\": \"64404fb50087fc1dda9a2e8cf2c100ef87bead52\", \"native_theme_unittests\": \"2c770788033cb31ce7a910c0cbfb479a7d22f70c\", \"net_unittests\": \"b42caf7dba0d07fa605a49879b9e90b74c08d1ea\", \"notification_helper_unittests\": \"f86040a71dfba4cf68153e1f897b20c4eec5a14d\", \"pdf_unittests\": \"0d3eaab6be2240cfdbb69e7bd742ec5f0538bfa8\", \"perfetto_unittests\": \"0cdda5fb1714d9c963bb7b03b4fc73cf3a2e0123\", \"ppapi_unittests\": \"aafddc7c20975af59c5c9e540c024aaebf9637a7\", \"printing_unittests\": \"bc85d9e819a06b01f642704fcec45dd59f63bed1\", \"remoting_unittests\": \"1b81a7af0203361f4d5c795005f24762d0389429\", \"sbox_integration_tests\": \"b4cc33443f0ef416f54fdbf72215bf8f2c2511c8\", \"sbox_unittests\": \"2327f7e72bc2dcd8cde8f208da45308d13dd803c\", \"sbox_validation_tests\": \"2d9bf615dc9df4dc6916bb58abc3a06c0e0e34a3\", \"service_manager_unittests\": \"c1e4f779d2bb7c05228dd38f29f83614831acb77\", \"services_unittests\": \"e8c712f0478cc8ed39629428e28dfa2c2b1a55c1\", \"setup_unittests\": \"54ae477cafafe4263b1645380858b8f432e13f09\", \"shell_dialogs_unittests\": \"8075831878c4a0c41a545a0a5c59bb6731d1e3e8\", \"skia_unittests\": \"ff80955c736721c826dbcefcd5c9e05c69db9ae2\", \"snapshot_unittests\": \"a8f90b448bccc8bdb9f7a3d7d0667d854e49c4d7\", \"sql_unittests\": \"02cfd58ed75e20ef8385687ef0bce4d021d77a31\", \"storage_unittests\": \"77c6501f0bd41835a81c8e2c51e04c73099c4445\", \"sync_integration_tests\": \"cf48d7ff4602b0e2fed77fcd06d0475fd17de9c2\", \"traffic_annotation_auditor_unittests\": \"711db2a359dc2a4b1f6f071ac7cc815bf112ccf4\", \"ui_base_unittests\": \"a1b131c7ea88f0e3870210d99c7c3dae9d03882a\", \"ui_touch_selection_unittests\": \"493b098bbdea6d9f0a9077b65634eae628e5f0b1\", \"unit_tests\": \"2c3c35de34e09a6353cd6bf30685a441443c5ee8\", \"updater_tests\": \"0c42924a4a4c603219ec6043a59937032d4f3420\", \"url_unittests\": \"b9f9501c1ddbc70b8e8de58943143fe0cd47f116\", \"views_unittests\": \"56a026c5901dac0e15cee536d3d439e9889e9590\", \"viz_unittests\": \"d1862cdcfba17001bba7d97bf8832138e8ca138b\", \"vr_common_unittests\": \"d39e2310c77a988357ff75d7fb900e75bdbdf765\", \"vr_pixeltests\": \"fa10674296d609298168a42d0ab1f47ce05455be\", \"weblayer_browsertests\": \"da3434c08c8e651c0c5d69f3807aee66b398e3f0\", \"weblayer_unittests\": \"0a4e0d2adb0cb4f6b896f5943c77eacc3ac68d83\", \"wm_unittests\": \"5c04d3df5fcec6ff5b007cca2d0ac882aff02cc5\", \"wtf_unittests\": \"5a15426c6368c0f17e32e767d641fcdccd3503c5\", \"zlib_unittests\": \"8d05b34c1745fd5be9008b0cc3048b4964142e04\", \"zucchini_unittests\": \"d3575d6467120e8f35e7adcf9a6b99d1da2fbc0b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609347462279703",
"status": "COMPLETED",
"status_changed_ts": "1609350784446218",
"tags": [
"build_address:luci.chromium.ci/win-asan/16557",
"builder:win-asan",
"buildset:commit/git/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fbc2c95ceecb194dcd0814d77cee6b8441cf609",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024900655576790960",
"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/8859470593198709328/+/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:50cd66f975e8ba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609350784515552",
"url": "https://ci.chromium.org/b/8859470593198709328",
"utcnow_ts": "1609350786621890"
}
}
[17:53:06.630] Invocation finished in 55m27.76442425s with status SUCCEEDED