[18:10:40.501] New invocation is queued and will start shortly
[18:10:41.621] Starting the invocation (attempt 1)
[18:10:41.658] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:10:41.658] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:10:41.659] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023084121787284496",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"eaa202637f5d897151194f5a30b0f45c81a2ca02\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023084121787284496",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"gitiles_ref:refs/heads/master"
]
}
[18:10:42.678] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611079841789185",
"id": "8857654059775020208",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eaa202637f5d897151194f5a30b0f45c81a2ca02\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611079842567746",
"tags": [
"build_address:luci.chromium.ci/win-asan/16896",
"builder:win-asan",
"buildset:commit/git/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023084121787284496",
"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/8857654059775020208/+/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": "1611079842567874",
"url": "https://ci.chromium.org/b/8857654059775020208",
"utcnow_ts": "1611079842670657"
}
}
[18:10:42.678] Task URL: https://ci.chromium.org/b/8857654059775020208
[18:10:42.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:2:0) after 1m0s
[18:10:45.493] Received PubSub notification, asking Buildbucket for the build status
[18:10:46.864] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:42.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:2:0)
[18:11:42.679] Timer tick, asking Buildbucket for the build status
[18:11:42.777] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:42.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:4:0) after 1m0s
[18:12:42.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:4:0)
[18:12:42.842] Timer tick, asking Buildbucket for the build status
[18:12:43.318] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:43.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:5:0) after 1m0s
[18:13:43.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:5:0)
[18:13:43.544] Timer tick, asking Buildbucket for the build status
[18:13:43.771] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:43.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:6:0) after 1m0s
[18:14:43.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:6:0)
[18:14:43.861] Timer tick, asking Buildbucket for the build status
[18:14:44.002] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:44.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:7:0) after 1m0s
[18:15:44.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:7:0)
[18:15:44.159] Timer tick, asking Buildbucket for the build status
[18:15:44.326] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:44.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:8:0) after 1m0s
[18:16:44.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:8:0)
[18:16:44.469] Timer tick, asking Buildbucket for the build status
[18:16:44.540] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:44.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:9:0) after 1m0s
[18:17:44.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:9:0)
[18:17:44.585] Timer tick, asking Buildbucket for the build status
[18:17:44.662] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:44.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:10:0) after 1m0s
[18:18:44.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:10:0)
[18:18:44.734] Timer tick, asking Buildbucket for the build status
[18:18:44.958] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:44.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:11:0) after 1m0s
[18:19:45.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:11:0)
[18:19:45.061] Timer tick, asking Buildbucket for the build status
[18:19:45.153] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:12:0) after 1m0s
[18:20:45.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:12:0)
[18:20:45.140] Timer tick, asking Buildbucket for the build status
[18:20:45.343] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:45.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:13:0) after 1m0s
[18:21:45.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:13:0)
[18:21:45.394] Timer tick, asking Buildbucket for the build status
[18:21:45.464] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:45.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:14:0) after 1m0s
[18:22:45.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:14:0)
[18:22:45.487] Timer tick, asking Buildbucket for the build status
[18:22:45.683] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:45.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:15:0) after 1m0s
[18:23:45.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:15:0)
[18:23:45.737] Timer tick, asking Buildbucket for the build status
[18:23:45.811] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:45.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:16:0) after 1m0s
[18:24:45.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:16:0)
[18:24:45.879] Timer tick, asking Buildbucket for the build status
[18:24:45.946] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:45.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:17:0) after 1m0s
[18:25:45.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:17:0)
[18:25:45.954] Timer tick, asking Buildbucket for the build status
[18:25:46.166] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:46.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:18:0) after 1m0s
[18:26:46.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:18:0)
[18:26:46.249] Timer tick, asking Buildbucket for the build status
[18:26:46.355] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:46.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:19:0) after 1m0s
[18:27:46.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:19:0)
[18:27:46.363] Timer tick, asking Buildbucket for the build status
[18:27:46.462] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:46.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:20:0) after 1m0s
[18:28:46.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:20:0)
[18:28:46.490] Timer tick, asking Buildbucket for the build status
[18:28:46.615] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:46.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:21:0) after 1m0s
[18:29:46.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:21:0)
[18:29:46.748] Timer tick, asking Buildbucket for the build status
[18:29:46.891] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:46.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:22:0) after 1m0s
[18:30:47.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:22:0)
[18:30:47.423] Timer tick, asking Buildbucket for the build status
[18:30:47.909] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:47.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:23:0) after 1m0s
[18:31:48.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:23:0)
[18:31:48.055] Timer tick, asking Buildbucket for the build status
[18:31:48.336] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:48.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:24:0) after 1m0s
[18:32:48.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:24:0)
[18:32:48.461] Timer tick, asking Buildbucket for the build status
[18:32:49.129] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:49.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:25:0) after 1m0s
[18:33:49.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:25:0)
[18:33:49.166] Timer tick, asking Buildbucket for the build status
[18:33:49.512] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:49.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:26:0) after 1m0s
[18:34:49.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:26:0)
[18:34:49.576] Timer tick, asking Buildbucket for the build status
[18:34:49.707] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:49.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:27:0) after 1m0s
[18:35:49.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:27:0)
[18:35:49.938] Timer tick, asking Buildbucket for the build status
[18:35:50.136] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:50.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:28:0) after 1m0s
[18:36:50.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:28:0)
[18:36:50.273] Timer tick, asking Buildbucket for the build status
[18:36:50.552] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:50.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:29:0) after 1m0s
[18:37:50.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:29:0)
[18:37:50.605] Timer tick, asking Buildbucket for the build status
[18:37:50.737] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:50.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:30:0) after 1m0s
[18:38:50.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:30:0)
[18:38:50.872] Timer tick, asking Buildbucket for the build status
[18:38:51.188] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:51.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:31:0) after 1m0s
[18:39:51.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:31:0)
[18:39:51.298] Timer tick, asking Buildbucket for the build status
[18:39:51.817] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:51.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:32:0) after 1m0s
[18:40:52.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:32:0)
[18:40:52.300] Timer tick, asking Buildbucket for the build status
[18:40:52.445] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:52.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:33:0) after 1m0s
[18:41:52.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:33:0)
[18:41:52.535] Timer tick, asking Buildbucket for the build status
[18:41:52.723] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:52.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:34:0) after 1m0s
[18:42:52.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:34:0)
[18:42:52.895] Timer tick, asking Buildbucket for the build status
[18:42:53.197] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:53.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:35:0) after 1m0s
[18:43:53.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:35:0)
[18:43:53.316] Timer tick, asking Buildbucket for the build status
[18:43:53.401] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:53.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:36:0) after 1m0s
[18:44:53.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:36:0)
[18:44:53.528] Timer tick, asking Buildbucket for the build status
[18:44:53.632] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:53.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:37:0) after 1m0s
[18:45:53.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:37:0)
[18:45:53.706] Timer tick, asking Buildbucket for the build status
[18:45:54.205] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:54.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:38:0) after 1m0s
[18:46:54.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:38:0)
[18:46:54.248] Timer tick, asking Buildbucket for the build status
[18:46:54.376] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:54.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:39:0) after 1m0s
[18:47:54.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:39:0)
[18:47:54.481] Timer tick, asking Buildbucket for the build status
[18:47:54.567] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:54.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:40:0) after 1m0s
[18:48:54.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:40:0)
[18:48:54.598] Timer tick, asking Buildbucket for the build status
[18:48:54.880] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:54.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:41:0) after 1m0s
[18:49:54.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:41:0)
[18:49:54.989] Timer tick, asking Buildbucket for the build status
[18:49:55.076] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:55.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:42:0) after 1m0s
[18:50:55.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:42:0)
[18:50:55.192] Timer tick, asking Buildbucket for the build status
[18:50:56.095] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:43:0) after 1m0s
[18:51:56.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:43:0)
[18:51:56.147] Timer tick, asking Buildbucket for the build status
[18:51:56.882] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:56.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:44:0) after 1m0s
[18:52:56.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:44:0)
[18:52:56.898] Timer tick, asking Buildbucket for the build status
[18:52:57.257] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:57.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:45:0) after 1m0s
[18:53:57.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:45:0)
[18:53:57.340] Timer tick, asking Buildbucket for the build status
[18:53:58.165] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:58.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:46:0) after 1m0s
[18:54:58.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:46:0)
[18:54:58.251] Timer tick, asking Buildbucket for the build status
[18:54:58.415] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:58.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:47:0) after 1m0s
[18:55:58.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:47:0)
[18:55:58.451] Timer tick, asking Buildbucket for the build status
[18:55:58.872] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:58.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:48:0) after 1m0s
[18:56:58.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:48:0)
[18:56:58.881] Timer tick, asking Buildbucket for the build status
[18:56:59.020] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:59.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:49:0) after 1m0s
[18:57:59.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:49:0)
[18:57:59.084] Timer tick, asking Buildbucket for the build status
[18:57:59.706] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:59.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:50:0) after 1m0s
[18:58:59.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:50:0)
[18:58:59.813] Timer tick, asking Buildbucket for the build status
[18:58:59.937] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:59.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:51:0) after 1m0s
[18:59:59.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:51:0)
[18:59:59.963] Timer tick, asking Buildbucket for the build status
[19:00:00.204] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:00.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:52:0) after 1m0s
[19:01:00.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:52:0)
[19:01:00.185] Timer tick, asking Buildbucket for the build status
[19:01:00.339] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:00.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:53:0) after 1m0s
[19:02:01.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:53:0)
[19:02:01.269] Timer tick, asking Buildbucket for the build status
[19:02:02.138] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:02.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:54:0) after 1m0s
[19:03:02.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:54:0)
[19:03:02.186] Timer tick, asking Buildbucket for the build status
[19:03:02.350] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:02.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:55:0) after 1m0s
[19:04:02.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:55:0)
[19:04:02.456] Timer tick, asking Buildbucket for the build status
[19:04:02.690] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:02.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:56:0) after 1m0s
[19:05:02.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:56:0)
[19:05:02.793] Timer tick, asking Buildbucket for the build status
[19:05:03.136] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:03.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:57:0) after 1m0s
[19:06:03.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:57:0)
[19:06:03.204] Timer tick, asking Buildbucket for the build status
[19:06:03.475] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:03.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:58:0) after 1m0s
[19:07:03.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:58:0)
[19:07:03.536] Timer tick, asking Buildbucket for the build status
[19:07:03.837] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:03.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:59:0) after 1m0s
[19:08:03.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:59:0)
[19:08:03.901] Timer tick, asking Buildbucket for the build status
[19:08:03.970] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:03.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:60:0) after 1m0s
[19:09:04.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:60:0)
[19:09:04.112] Timer tick, asking Buildbucket for the build status
[19:09:04.306] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:04.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:61:0) after 1m0s
[19:10:04.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:61:0)
[19:10:04.328] Timer tick, asking Buildbucket for the build status
[19:10:04.473] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:04.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:62:0) after 1m0s
[19:11:04.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:62:0)
[19:11:04.500] Timer tick, asking Buildbucket for the build status
[19:11:04.680] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:04.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:63:0) after 1m0s
[19:12:04.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:63:0)
[19:12:04.723] Timer tick, asking Buildbucket for the build status
[19:12:04.795] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:04.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:64:0) after 1m0s
[19:13:04.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:64:0)
[19:13:04.803] Timer tick, asking Buildbucket for the build status
[19:13:04.957] Build 8857654059775020208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:04.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023084121787284496:65:0) after 1m0s
[19:13:52.666] Received PubSub notification, asking Buildbucket for the build status
[19:13:52.744] Build 8857654059775020208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:13:52.744] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611083630299272",
"created_by": "project:chromium",
"created_ts": "1611079841789185",
"id": "8857654059775020208",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eaa202637f5d897151194f5a30b0f45c81a2ca02\"}}",
"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\": \"eaa202637f5d897151194f5a30b0f45c81a2ca02\", \"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-0-nszv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16896, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"b69f54069cbf700a4646817805ca77c0d2a01292\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"eaa202637f5d897151194f5a30b0f45c81a2ca02\", \"got_revision_cp\": \"refs/heads/master@{#844817}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"b28c3b8fed06f17e8d2dc3c265107d15ddba1185\", \"got_v8_revision_cp\": \"refs/heads/9.0.14@{#1}\", \"got_webrtc_revision\": \"6cdb67fb33ed6fcda5f1354b64458d82ccfc8fb2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33032}\", \"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\": \"eaa202637f5d897151194f5a30b0f45c81a2ca02\", \"swarm_hashes_refs/heads/master(at){#844817}_without_patch\": {\"absl_hardening_tests\": \"eed92c43599232bfcc016a84ee88e86c796635e2\", \"accessibility_unittests\": \"4140b76452a23ccab1b2d2f1db81133bdf5ce90b\", \"angle_unittests\": \"0317f036400b1eff43e9979fcdaae51ff1afd605\", \"app_shell_unittests\": \"02e161b586122826b8f18be153afa67899c8b7c9\", \"aura_unittests\": \"75e14fabfc87e92931c0cd61a0fd7ff7132b0e15\", \"base_unittests\": \"dbd0284736c9ea997a16091578695377ce97b158\", \"base_util_unittests\": \"f4c49e1e855bddccff41ac0446144093180a39d4\", \"blink_common_unittests\": \"643371a315e5d353cb8e47b27468fd93679f3879\", \"blink_fuzzer_unittests\": \"f43b5e1edafb9037395a2cf195e297d062ff349f\", \"blink_heap_unittests\": \"e1c4bba8a1f6a2cdc4d4b3237c257f5ed403e3a7\", \"blink_platform_unittests\": \"847eeb76ef1522a111f9a46ff376e7bf4fa3de2f\", \"blink_unittests\": \"56bf627eede4298436a6878d43abde651a243920\", \"boringssl_crypto_tests\": \"bc5d33a1097f049a099861a6c8638b7ee00292ac\", \"boringssl_ssl_tests\": \"76e657d731aae1dd5b0b0fa674d33bed298247f3\", \"browser_switcher_bho_unittests\": \"17acbe9a6c516e8d9b1a8c9fbd27a1fad8081bb4\", \"browser_tests\": \"02e74454f9073a7217d4d0e10dda16c963056b5d\", \"capture_unittests\": \"798cc346bcc4a51f468f0b2e00f6b30105496962\", \"cast_unittests\": \"cd88bab81f8ad971618b8917e0b93347a1f28be9\", \"cc_unittests\": \"6b158fe5f724c359c5ed66527c3ea6f40786c0e7\", \"chrome_app_unittests\": \"f82326f3169c3cb236e224cb3dfada32bb0bf2e6\", \"chrome_cleaner_unittests\": \"278e85a98362e8eaca0c445e461412601759eb86\", \"chrome_elf_unittests\": \"7b942417657f8f693d3edc9bb1c32edcea0c4505\", \"chromedriver_unittests\": \"0e447fe7dfa2109de239e1e71ed719228545d7b1\", \"components_browsertests\": \"0be8b664f2b945df01ee27f50247b70e28f61cf4\", \"components_unittests\": \"8fb3b9443207e8608ab56592d42f676fa243b931\", \"compositor_unittests\": \"1135b0ce5f7de234c14a9a38307236e3fe88dbd1\", \"content_browsertests\": \"5fcb19bff6dbe58f27fd5c1a46fd0ef5c2d54717\", \"content_unittests\": \"0174d8a69722f04791be177a40b4503492356f0e\", \"courgette_unittests\": \"61812110f8bdd381d2e6cdd39d9e95988e43b1eb\", \"crashpad_tests\": \"45c4b8372a1ae05dac5e596d9ec1775f20bb2a4b\", \"cronet_tests\": \"4b026f9909e8dadcc4aa27493cb8754f6a64959f\", \"cronet_unittests\": \"90b9b721bb6399c5ec13e40a8545d597618c0106\", \"crypto_unittests\": \"aa3b06ae2d606a3f49f4889a04b891592c862702\", \"delayloads_unittests\": \"4d8043be302138ea31d0c927b6fede52f2f11fb0\", \"device_unittests\": \"0c32134d23136a5beaf22bb4708de9c00f8c719b\", \"display_unittests\": \"33d370fef6c9ca69796208a9787f00bbee1002cc\", \"elevation_service_unittests\": \"7bd62f08a5bbddbae75d686c829690500a9791f7\", \"events_unittests\": \"feaa11d6e85e80fc85fbccdc93f8581e45c0f0de\", \"extensions_browsertests\": \"05ef245dca458838bd19c31a987bca1db80577e1\", \"extensions_unittests\": \"53afa73ab601123569a1c99364ba2a4cbc3bcd3e\", \"filesystem_service_unittests\": \"acd201ed4a5ca85553f3bfa897a01bebf825f616\", \"gcm_unit_tests\": \"b734a482746c148704b95c686bab0ffdb2f59637\", \"gcp_unittests\": \"2e7744c6a342397e06e8bf56b9e644207662d0fe\", \"gfx_unittests\": \"1de5e91da1fb5f9b3d3af5f2c3f3892ab605dcdd\", \"gin_unittests\": \"cfe6f17453d44b771563dad1935c78927a03a625\", \"google_apis_unittests\": \"54fa765cee88eb11cf25c4b4c563e49717dc916e\", \"gpu_unittests\": \"728732d80275924aa4801f8254f84694039db181\", \"gwp_asan_unittests\": \"f7b5d5e9a9eac295650e70b1d051a789363d7eb2\", \"headless_browsertests\": \"f4cafd67e75c148e2c115e7416c003ac7c0b4740\", \"headless_unittests\": \"c9e029c1d5d26d513c681ae7a5edab5932ba0ca2\", \"install_static_unittests\": \"77f6cbde26dec86b4a5879511695b31d91a45a9c\", \"installer_util_unittests\": \"8bfb22b9c0e148917ad3d2e0e7384e8f7604ec3f\", \"interactive_ui_tests\": \"db0bcb63f0ee1a0e6eaf223aa186846e3e9549df\", \"ipc_tests\": \"7ed51d370ae5b029f0b997c1bf0ac4eb54fd8efc\", \"jingle_unittests\": \"7db17b7f44c042693e5142d0efb057b68f597b84\", \"latency_unittests\": \"e358f275a56f378f31a425d28ebfbf6bfb3d3c7a\", \"libjingle_xmpp_unittests\": \"e2813ad8c783567d1aef5f0a532898ee75c892a0\", \"liburlpattern_unittests\": \"5a043d23caac014165d77fb1b2a670401f7c9235\", \"media_blink_unittests\": \"82ca7c1241e10e60c51455de6d5596c1dfe5a57f\", \"media_unittests\": \"4e3b9d6af639459b910a54f7e3f80eb30dc5c743\", \"message_center_unittests\": \"9d75c81bc63cbb75c3b11716a54bbfcdcbb328cf\", \"midi_unittests\": \"9ecd52143e83bc47032e74f1ab895ff5e85fa0cc\", \"mojo_core_unittests\": \"6207afcc95e06cb9fc8a5f5fc7e1b8c982f2ad56\", \"mojo_unittests\": \"a7deeccd05509aa586a105fa6cd00660086c0c9e\", \"nacl_loader_unittests\": \"0c667b9a8921722d1564e820ec76d0e845280f45\", \"native_theme_unittests\": \"8b07efe254f8c059fea5bf8c55ddb8ede1e27bab\", \"net_unittests\": \"7883d4a37ca9fd516a59c66f0c9759cc234482b8\", \"notification_helper_unittests\": \"d08dfeb6481ceed0dd2ff3f442f455e34f2839ae\", \"pdf_unittests\": \"6ac1989e50fb08b3f14112ec96ef30e4b2b5e16a\", \"perfetto_unittests\": \"c6bc5fc512a250e5702f41aa646de6245423cdb2\", \"ppapi_unittests\": \"280a18b5a088602be531d3be942532e0d70019ce\", \"printing_unittests\": \"0d6042b420d137b47fcdde85ff226833b570d5a0\", \"remoting_unittests\": \"073c88dce147b93421fbfdd2fbcfb01a48cf1227\", \"sbox_integration_tests\": \"5cfc35d74db2efe9fd5ffaa5e2a34859df8678f8\", \"sbox_unittests\": \"0b028ceea80433cf3d72b8e12b9d2d9bdec3d989\", \"sbox_validation_tests\": \"3cea6d9eeaeab3e18809f7e505882ce4ad06ce94\", \"service_manager_unittests\": \"e552f0ffcea05149cc69b995668dd22a97035eb6\", \"services_unittests\": \"f75cb365ae84f67b0ebbb091ff7d5e4732c54cd5\", \"setup_unittests\": \"0d48ab6dc093e6c9481c13269bb9e812f4a30367\", \"shell_dialogs_unittests\": \"f87671ba00c3b23915f8e9fe4acc280dfcb339fe\", \"skia_unittests\": \"c7a2046fdea1d45ff6c0129d284d9d166e0e764e\", \"snapshot_unittests\": \"05ff85cee9808fc77233ba8954da97b885f7fd55\", \"sql_unittests\": \"997444a0d81c8ca000eee269aab572dae968b353\", \"storage_unittests\": \"d77c260c65b9132d5c70a436b0570e4ddb09b055\", \"sync_integration_tests\": \"5260097e8c639cece1ab25d744665187f8ebe428\", \"traffic_annotation_auditor_unittests\": \"d0976d0f1928a8088d56a792bb0c5daf430413a5\", \"ui_base_unittests\": \"3ff401c2459bfc7a13df228a10949870a494a3b4\", \"ui_touch_selection_unittests\": \"4bbf37b431621a728892e9a9e980f0dd6d21b454\", \"unit_tests\": \"60b05dc3d944ec1405c49f4ffb367469732793b1\", \"updater_tests\": \"74ec70c914628acf70ad3c809026381ac73d903e\", \"url_unittests\": \"5ff5e30eddb2bc440fa3da76374383a4aa1d3c11\", \"views_unittests\": \"92e3ef55de372895129022d872fef88b027829f4\", \"viz_unittests\": \"e18d72db62fea655dfd214c63a563fe915fe7542\", \"vr_common_unittests\": \"b54b06612e8824d7b1ca4c0349927c1cecc1345a\", \"vr_pixeltests\": \"10f8cc5c5930841f4165732f8e9bf68340ae50e0\", \"weblayer_browsertests\": \"6714845b50c33bd0ecfbc5507fbd9cb81ca0f997\", \"weblayer_unittests\": \"cfa139dd85354fe065c0cdd750415e3cd5ce14fa\", \"wm_unittests\": \"02ce1659eddc6715d5194bbe685a838a3000b910\", \"wtf_unittests\": \"13c891e36f3c11ad8b7ad427986544ea66101470\", \"zlib_unittests\": \"a611ea2b1158e5401e63e83cd03366c322966ebd\", \"zucchini_unittests\": \"035aa851c72e8a8fae12d88bcb0e760be63d45cc\"}}, \"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-0-nszv\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611079844792983",
"status": "COMPLETED",
"status_changed_ts": "1611083630994747",
"tags": [
"build_address:luci.chromium.ci/win-asan/16896",
"builder:win-asan",
"buildset:commit/git/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eaa202637f5d897151194f5a30b0f45c81a2ca02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023084121787284496",
"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/8857654059775020208/+/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:5134a903c8e83f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611083631089421",
"url": "https://ci.chromium.org/b/8857654059775020208",
"utcnow_ts": "1611083632735332"
}
}
[19:13:52.744] Invocation finished in 1h3m12.258277998s with status SUCCEEDED