[13:22:46.511] New invocation is queued and will start shortly
[13:22:47.606] Starting the invocation (attempt 1)
[13:22:47.658] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:22:47.658] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:22:47.658] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020203131954137648",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c7357ea03d7df6c60ac14739f48c568be2c3854d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020203131954137648",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"gitiles_ref:refs/heads/master"
]
}
[13:22:48.493] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613827367767187",
"id": "8854773069974824992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c7357ea03d7df6c60ac14739f48c568be2c3854d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613827368245704",
"tags": [
"build_address:luci.chromium.ci/win-asan/17442",
"builder:win-asan",
"buildset:commit/git/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020203131954137648",
"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/8854773069974824992/+/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": "1613827368245816",
"url": "https://ci.chromium.org/b/8854773069974824992",
"utcnow_ts": "1613827368483701"
}
}
[13:22:48.493] Task URL: https://ci.chromium.org/b/8854773069974824992
[13:22:48.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:2:0) after 1m0s
[13:22:51.232] Received PubSub notification, asking Buildbucket for the build status
[13:22:51.329] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:2:0)
[13:23:48.565] Timer tick, asking Buildbucket for the build status
[13:23:48.634] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:4:0) after 1m0s
[13:24:48.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:4:0)
[13:24:48.745] Timer tick, asking Buildbucket for the build status
[13:24:48.827] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:48.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:5:0) after 1m0s
[13:25:48.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:5:0)
[13:25:48.863] Timer tick, asking Buildbucket for the build status
[13:25:49.197] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:49.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:6:0) after 1m0s
[13:26:49.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:6:0)
[13:26:49.246] Timer tick, asking Buildbucket for the build status
[13:26:49.310] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:7:0) after 1m0s
[13:27:49.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:7:0)
[13:27:49.452] Timer tick, asking Buildbucket for the build status
[13:27:49.543] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:49.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:8:0) after 1m0s
[13:28:49.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:8:0)
[13:28:49.636] Timer tick, asking Buildbucket for the build status
[13:28:49.747] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:49.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:9:0) after 1m0s
[13:29:49.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:9:0)
[13:29:49.914] Timer tick, asking Buildbucket for the build status
[13:29:50.128] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:50.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:10:0) after 1m0s
[13:30:50.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:10:0)
[13:30:50.222] Timer tick, asking Buildbucket for the build status
[13:30:50.320] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:50.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:11:0) after 1m0s
[13:31:50.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:11:0)
[13:31:50.449] Timer tick, asking Buildbucket for the build status
[13:31:50.597] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:50.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:12:0) after 1m0s
[13:32:50.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:12:0)
[13:32:50.612] Timer tick, asking Buildbucket for the build status
[13:32:50.685] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:50.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:13:0) after 1m0s
[13:33:50.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:13:0)
[13:33:50.700] Timer tick, asking Buildbucket for the build status
[13:33:50.799] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:50.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:14:0) after 1m0s
[13:34:50.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:14:0)
[13:34:50.865] Timer tick, asking Buildbucket for the build status
[13:34:51.046] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:51.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:15:0) after 1m0s
[13:35:51.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:15:0)
[13:35:51.157] Timer tick, asking Buildbucket for the build status
[13:35:51.234] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:51.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:16:0) after 1m0s
[13:36:51.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:16:0)
[13:36:51.270] Timer tick, asking Buildbucket for the build status
[13:36:51.343] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:51.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:17:0) after 1m0s
[13:37:51.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:17:0)
[13:37:51.350] Timer tick, asking Buildbucket for the build status
[13:37:51.451] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:51.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:18:0) after 1m0s
[13:38:51.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:18:0)
[13:38:51.467] Timer tick, asking Buildbucket for the build status
[13:38:51.838] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:51.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:19:0) after 1m0s
[13:39:51.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:19:0)
[13:39:51.856] Timer tick, asking Buildbucket for the build status
[13:39:51.923] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:20:0) after 1m0s
[13:40:51.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:20:0)
[13:40:51.944] Timer tick, asking Buildbucket for the build status
[13:40:52.023] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:52.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:21:0) after 1m0s
[13:41:52.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:21:0)
[13:41:52.046] Timer tick, asking Buildbucket for the build status
[13:41:52.127] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:52.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:22:0) after 1m0s
[13:42:52.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:22:0)
[13:42:52.143] Timer tick, asking Buildbucket for the build status
[13:42:52.223] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:52.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:23:0) after 1m0s
[13:43:52.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:23:0)
[13:43:52.201] Timer tick, asking Buildbucket for the build status
[13:43:52.660] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:52.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:24:0) after 1m0s
[13:44:52.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:24:0)
[13:44:52.631] Timer tick, asking Buildbucket for the build status
[13:44:52.747] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:52.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:25:0) after 1m0s
[13:45:52.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:25:0)
[13:45:52.784] Timer tick, asking Buildbucket for the build status
[13:45:52.962] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:52.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:26:0) after 1m0s
[13:46:53.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:26:0)
[13:46:53.677] Timer tick, asking Buildbucket for the build status
[13:46:53.783] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:53.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:27:0) after 1m0s
[13:47:53.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:27:0)
[13:47:53.802] Timer tick, asking Buildbucket for the build status
[13:47:54.011] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:54.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:28:0) after 1m0s
[13:48:54.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:28:0)
[13:48:54.097] Timer tick, asking Buildbucket for the build status
[13:48:54.611] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:54.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:29:0) after 1m0s
[13:49:54.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:29:0)
[13:49:54.694] Timer tick, asking Buildbucket for the build status
[13:49:54.784] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:30:0) after 1m0s
[13:50:54.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:30:0)
[13:50:54.877] Timer tick, asking Buildbucket for the build status
[13:50:54.971] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:54.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:31:0) after 1m0s
[13:51:54.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:31:0)
[13:51:54.990] Timer tick, asking Buildbucket for the build status
[13:51:55.334] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:55.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:32:0) after 1m0s
[13:52:55.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:32:0)
[13:52:55.369] Timer tick, asking Buildbucket for the build status
[13:52:55.450] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:55.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:33:0) after 1m0s
[13:53:56.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:33:0)
[13:53:56.324] Timer tick, asking Buildbucket for the build status
[13:53:56.412] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:56.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:34:0) after 1m0s
[13:54:56.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:34:0)
[13:54:56.454] Timer tick, asking Buildbucket for the build status
[13:54:56.548] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:56.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:35:0) after 1m0s
[13:55:56.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:35:0)
[13:55:56.863] Timer tick, asking Buildbucket for the build status
[13:55:57.235] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:57.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:36:0) after 1m0s
[13:56:57.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:36:0)
[13:56:57.358] Timer tick, asking Buildbucket for the build status
[13:56:57.595] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:57.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:37:0) after 1m0s
[13:57:57.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:37:0)
[13:57:57.772] Timer tick, asking Buildbucket for the build status
[13:57:57.844] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:38:0) after 1m0s
[13:58:57.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:38:0)
[13:58:57.856] Timer tick, asking Buildbucket for the build status
[13:58:57.958] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:57.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:39:0) after 1m0s
[13:59:58.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:39:0)
[13:59:58.123] Timer tick, asking Buildbucket for the build status
[13:59:58.212] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:58.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:40:0) after 1m0s
[14:00:58.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:40:0)
[14:00:58.220] Timer tick, asking Buildbucket for the build status
[14:00:58.289] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:58.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:41:0) after 1m0s
[14:01:58.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:41:0)
[14:01:58.298] Timer tick, asking Buildbucket for the build status
[14:01:58.419] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:58.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:42:0) after 1m0s
[14:02:58.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:42:0)
[14:02:58.472] Timer tick, asking Buildbucket for the build status
[14:02:58.553] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:43:0) after 1m0s
[14:03:58.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:43:0)
[14:03:58.702] Timer tick, asking Buildbucket for the build status
[14:03:59.006] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:59.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:44:0) after 1m0s
[14:04:59.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:44:0)
[14:04:59.092] Timer tick, asking Buildbucket for the build status
[14:04:59.187] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:59.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:45:0) after 1m0s
[14:05:59.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:45:0)
[14:05:59.306] Timer tick, asking Buildbucket for the build status
[14:05:59.414] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:46:0) after 1m0s
[14:06:59.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:46:0)
[14:06:59.474] Timer tick, asking Buildbucket for the build status
[14:06:59.556] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:59.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:47:0) after 1m0s
[14:07:59.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:47:0)
[14:07:59.647] Timer tick, asking Buildbucket for the build status
[14:07:59.752] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:59.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:48:0) after 1m0s
[14:08:59.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:48:0)
[14:08:59.772] Timer tick, asking Buildbucket for the build status
[14:08:59.858] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:59.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:49:0) after 1m0s
[14:09:59.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:49:0)
[14:09:59.941] Timer tick, asking Buildbucket for the build status
[14:10:00.126] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:00.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:50:0) after 1m0s
[14:11:00.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:50:0)
[14:11:00.160] Timer tick, asking Buildbucket for the build status
[14:11:00.262] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:00.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:51:0) after 1m0s
[14:12:00.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:51:0)
[14:12:00.281] Timer tick, asking Buildbucket for the build status
[14:12:00.528] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:00.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:52:0) after 1m0s
[14:13:00.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:52:0)
[14:13:00.662] Timer tick, asking Buildbucket for the build status
[14:13:01.075] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:01.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:53:0) after 1m0s
[14:14:01.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:53:0)
[14:14:01.266] Timer tick, asking Buildbucket for the build status
[14:14:01.359] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:01.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:54:0) after 1m0s
[14:15:01.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:54:0)
[14:15:01.336] Timer tick, asking Buildbucket for the build status
[14:15:01.624] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:01.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:55:0) after 1m0s
[14:16:01.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:55:0)
[14:16:01.687] Timer tick, asking Buildbucket for the build status
[14:16:01.995] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:01.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:56:0) after 1m0s
[14:17:02.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:56:0)
[14:17:02.196] Timer tick, asking Buildbucket for the build status
[14:17:02.315] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:02.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:57:0) after 1m0s
[14:18:02.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:57:0)
[14:18:02.436] Timer tick, asking Buildbucket for the build status
[14:18:02.798] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:02.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:58:0) after 1m0s
[14:19:02.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:58:0)
[14:19:02.981] Timer tick, asking Buildbucket for the build status
[14:19:03.157] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:03.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:59:0) after 1m0s
[14:20:03.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:59:0)
[14:20:03.243] Timer tick, asking Buildbucket for the build status
[14:20:03.362] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:03.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:60:0) after 1m0s
[14:21:03.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:60:0)
[14:21:03.557] Timer tick, asking Buildbucket for the build status
[14:21:03.707] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:03.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:61:0) after 1m0s
[14:22:03.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:61:0)
[14:22:03.928] Timer tick, asking Buildbucket for the build status
[14:22:04.098] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:62:0) after 1m0s
[14:23:04.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:62:0)
[14:23:04.297] Timer tick, asking Buildbucket for the build status
[14:23:04.624] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:63:0) after 1m0s
[14:24:04.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:63:0)
[14:24:04.750] Timer tick, asking Buildbucket for the build status
[14:24:04.903] Build 8854773069974824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:04.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020203131954137648:64:0) after 1m0s
[14:24:57.485] Received PubSub notification, asking Buildbucket for the build status
[14:24:57.790] Build 8854773069974824992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:24:57.790] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613831096795004",
"created_by": "project:chromium",
"created_ts": "1613827367767187",
"id": "8854773069974824992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c7357ea03d7df6c60ac14739f48c568be2c3854d\"}}",
"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\": \"c7357ea03d7df6c60ac14739f48c568be2c3854d\", \"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-ivw4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17442, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"d3d30921fc4c74ff24d27c1ad038445fa497046e\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c7357ea03d7df6c60ac14739f48c568be2c3854d\", \"got_revision_cp\": \"refs/heads/master@{#856095}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"c7357ea03d7df6c60ac14739f48c568be2c3854d\", \"swarm_hashes_refs/heads/master(at){#856095}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"28dccfff10321c1b74f33e05315ae5bb02a7071f\", \"angle_unittests\": \"6eb4889959e06307e94f3275d21a80d7855d292e\", \"app_shell_unittests\": \"34a670341d5d11bccd108bf8b7b45550ed215fef\", \"aura_unittests\": \"d1efdaafa5e7b46b183e334cf34e7481963f38a0\", \"base_unittests\": \"1ceb22df0f743b895465b4bef1cb2600b451aa3c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"bede4a74da7bd5dfeaa0969de67bb86526c244a1\", \"blink_fuzzer_unittests\": \"49d488efb0d039e52cdc6e573c9b927b24782950\", \"blink_heap_unittests\": \"168227c21b80a08b2044168d064087234019e01c\", \"blink_platform_unittests\": \"bcae859455fc6dbbf640247370366723a35025a8\", \"blink_unittests\": \"e50093d467b3306051603a6cb53f0a1d6c697c96\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"248cc5d15a57eb4d38453735869390040d4e0ff5\", \"capture_unittests\": \"8d3fb0bca6cafca25e982ddeb35bb3007fe96051\", \"cast_unittests\": \"e3b7519c8c4c2b9abd19b7e9ff82a87232171b49\", \"cc_unittests\": \"1f4c2d836236e2a689bd838f97689d6cc5699f17\", \"chrome_app_unittests\": \"a58e5a9597758321531cd5f888ac42f7556a8d7e\", \"chrome_cleaner_unittests\": \"91d68a1458ca61d1d8f74a4ebc1583b20ef3ef29\", \"chrome_elf_unittests\": \"07c3930e6b86e313d71083c46f77fd89e7161890\", \"chromedriver_unittests\": \"799b19dc1974d8deecf59bd82aaf50a7e47da499\", \"components_browsertests\": \"f48af455985d11a23dd515a79f0936b273c70803\", \"components_unittests\": \"5526c82ce63ca66bd71f0ad1fd4030a3bed120b2\", \"compositor_unittests\": \"442cc9369d489569aea399511abd4de6c719147d\", \"content_browsertests\": \"ba36f65cb61af7f46deca40ed04c0b742a8804f9\", \"content_unittests\": \"3ea6eccf747d7500084ddba5c92b8732199049c3\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"67c191b37e845456467c76bfffae52df04f9a7d1\", \"cronet_unittests\": \"9a4c6135488b0526610b880f4673604851679d33\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"9853dbb150b142d181c786f1ca48ac575e24403c\", \"device_unittests\": \"b5e67b0991ef1d2489807a281bf63563ee2f4753\", \"display_unittests\": \"d887f579bd6419e36dd5d620fefd2109c3a95f37\", \"elevation_service_unittests\": \"f79dddb7e17ab12f2842e2d1465df26d89e85d85\", \"events_unittests\": \"a3facfcbce941ac97eac73f851d6f0aaebf3b5fe\", \"extensions_browsertests\": \"e05e793e523d0354b853dbb392e1f750d3946796\", \"extensions_unittests\": \"a543876903927693ca7665e953d7cb5d4d65ac98\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"4261adee6cb8bc4958d32dfebd5c6dd2e19160e3\", \"gcp_unittests\": \"da0228e950ccca003ff90c3f716c46f5723e8bfa\", \"gfx_unittests\": \"60d7597e08ff41aaca5d05b897700fb737c43742\", \"gin_unittests\": \"4870a5d97c4ee8fa44241007482db1c691266529\", \"google_apis_unittests\": \"1a679bc505c4e9d085c87940e224314b4af29e91\", \"gpu_unittests\": \"f1bd8c4734bfed5187569131e60619598f236e64\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"0fac8dae85d2990fb7107ceef1843ef9c662d335\", \"headless_unittests\": \"e579ca19f721effd597fa57617dbc2163c2f4409\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"ef1c915af9da4ecc0d7afb73b709f006545f502d\", \"interactive_ui_tests\": \"390ebd74409dd43d7edead7e70e9e79164283020\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"bdf364b0f8de72888e9efc5d590d5bc540bfebeb\", \"latency_unittests\": \"fccf2fb57ec02e221b2b50dc0c5cd2cbc44ee6a9\", \"libjingle_xmpp_unittests\": \"153d75fb0f9d4b3fdaf953c090872b8dfb585aa2\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"902565634500e1a52f0420141106ff051b15937c\", \"media_unittests\": \"80854ad8d1b95170c07f283515381b55c75f32d3\", \"message_center_unittests\": \"7e486965245d3ed450d240efd69160abadc0d313\", \"midi_unittests\": \"98373d8b72aae903046e691418cfa863230caa2d\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"492f4a3e9f758b768ee307e0b12b30abaa509a96\", \"nacl_loader_unittests\": \"85824cae1c741f8a6de9c01a99212f47eda6e478\", \"native_theme_unittests\": \"a97b91beebe4744ef8404b4c54557f4cb701142d\", \"net_unittests\": \"fdab5d3051b6e74b2299c4e46be6fe62ed20c2a5\", \"notification_helper_unittests\": \"96a9272d0aaaca19001cd36efdffb4dae05161c7\", \"pdf_unittests\": \"0aefe0b7f3da5cfdee2264203fd5c17165d91600\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"047910343d7c8d84ace1ef3e4891a7730d26ca45\", \"printing_unittests\": \"3b75c6e78d8fd99af33d724d5f4db85517d20dc8\", \"remoting_unittests\": \"90ba4398af1ee4153026e615cdb60968d02005ac\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"eb43e55b6036b8b1d327abdb7c9c1cfcf26c4efc\", \"services_unittests\": \"b0d1b2739917ea2a3fc8b706f09e7133ff6ba49a\", \"setup_unittests\": \"238d233aa8b36e0d023342af996e6d1fb4dcd79c\", \"shell_dialogs_unittests\": \"13f2476e62982bfe068d7d4d46e9526eafe962ab\", \"skia_unittests\": \"c8dee1bb456f9b3f57afb5198b0eba4a8e965d97\", \"snapshot_unittests\": \"6adc64bf632d76c77810131c53fea721ad71c2cf\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"03694d30c58d89bb9b6c60c273963a8c2d23126f\", \"sync_integration_tests\": \"aa0937c134d1069b2c1c15366e8a37a364087655\", \"traffic_annotation_auditor_unittests\": \"774059a4d1525c99a60dd3eee241c4046b561136\", \"ui_base_unittests\": \"35939602fe37f154dd927c603854e35337ea6d27\", \"ui_touch_selection_unittests\": \"eb67971cd984b97d7f263bd36696966e205b00cb\", \"unit_tests\": \"17ced4959d51ab95ddf3ce03db41a78e76878c63\", \"updater_tests\": \"ba2111a86eb80a8899178a43751d0a21940bfb5b\", \"url_unittests\": \"9e21669aae25157e821cf8e8da3282e69e2d943c\", \"views_unittests\": \"36cbbf181f2d5f66c9fdca3cf2330fe4392fbabb\", \"viz_unittests\": \"832d52d2aa711a8234df1b10d6599c3dd0c98508\", \"vr_common_unittests\": \"f3018a174f251aa71c518a317ac007a30f67e447\", \"vr_pixeltests\": \"cfcb075ddfffe2daa70e34cc40ee45b5fba8f21a\", \"weblayer_browsertests\": \"d8d93057c02cb441e5b37ff54bd5c43b2f6c8bf9\", \"weblayer_unittests\": \"b0dae2a47a611cb93b4077fc66178aad236fba94\", \"wm_unittests\": \"2929f33749b89245f6d79bea6342804b02d02df5\", \"wtf_unittests\": \"6cfee1478fb9ed4fb7528479b288b9cfc95cbfc0\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"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-ivw4\"], \"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\": [\"5706-397711c\"], \"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": "1613827370661947",
"status": "COMPLETED",
"status_changed_ts": "1613831097078692",
"tags": [
"build_address:luci.chromium.ci/win-asan/17442",
"builder:win-asan",
"buildset:commit/git/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c7357ea03d7df6c60ac14739f48c568be2c3854d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020203131954137648",
"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/8854773069974824992/+/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:51d86cf4826dec10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613831097084363",
"url": "https://ci.chromium.org/b/8854773069974824992",
"utcnow_ts": "1613831097723947"
}
}
[14:24:57.790] Invocation finished in 1h2m11.294057933s with status SUCCEEDED