[01:42:13.507] New invocation is queued and will start shortly
[01:42:14.617] Starting the invocation (attempt 1)
[01:42:14.655] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:14.655] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:14.655] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027857351930122576",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8b265a247dffc48324cd127528a9b36cd2b8329d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027857351930122576",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8b265a247dffc48324cd127528a9b36cd2b8329d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b265a247dffc48324cd127528a9b36cd2b8329d",
"gitiles_ref:refs/heads/master"
]
}
[01:42:15.962] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606527734788640",
"id": "8862427289925500736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b265a247dffc48324cd127528a9b36cd2b8329d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606527735785992",
"tags": [
"build_address:luci.chromium.ci/win-asan/15897",
"builder:win-asan",
"buildset:commit/git/8b265a247dffc48324cd127528a9b36cd2b8329d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b265a247dffc48324cd127528a9b36cd2b8329d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027857351930122576",
"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/8862427289925500736/+/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": "1606527735786193",
"url": "https://ci.chromium.org/b/8862427289925500736",
"utcnow_ts": "1606527735954892"
}
}
[01:42:15.962] Task URL: https://ci.chromium.org/b/8862427289925500736
[01:42:15.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:2:0) after 1m0s
[01:42:19.212] Received PubSub notification, asking Buildbucket for the build status
[01:42:19.278] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:16.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:2:0)
[01:43:16.019] Timer tick, asking Buildbucket for the build status
[01:43:16.167] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:16.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:4:0) after 1m0s
[01:44:16.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:4:0)
[01:44:16.240] Timer tick, asking Buildbucket for the build status
[01:44:16.509] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:16.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:5:0) after 1m0s
[01:45:16.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:5:0)
[01:45:16.549] Timer tick, asking Buildbucket for the build status
[01:45:16.661] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:16.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:6:0) after 1m0s
[01:46:16.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:6:0)
[01:46:16.644] Timer tick, asking Buildbucket for the build status
[01:46:16.757] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:16.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:7:0) after 1m0s
[01:47:16.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:7:0)
[01:47:16.777] Timer tick, asking Buildbucket for the build status
[01:47:16.887] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:16.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:8:0) after 1m0s
[01:48:16.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:8:0)
[01:48:16.958] Timer tick, asking Buildbucket for the build status
[01:48:17.212] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:17.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:9:0) after 1m0s
[01:49:17.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:9:0)
[01:49:17.228] Timer tick, asking Buildbucket for the build status
[01:49:17.512] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:17.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:10:0) after 1m0s
[01:50:17.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:10:0)
[01:50:17.570] Timer tick, asking Buildbucket for the build status
[01:50:17.727] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:17.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:11:0) after 1m0s
[01:51:17.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:11:0)
[01:51:17.749] Timer tick, asking Buildbucket for the build status
[01:51:17.828] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:17.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:12:0) after 1m0s
[01:52:17.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:12:0)
[01:52:17.983] Timer tick, asking Buildbucket for the build status
[01:52:18.078] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:18.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:13:0) after 1m0s
[01:53:18.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:13:0)
[01:53:18.099] Timer tick, asking Buildbucket for the build status
[01:53:18.539] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:18.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:14:0) after 1m0s
[01:54:19.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:14:0)
[01:54:19.912] Timer tick, asking Buildbucket for the build status
[01:54:20.046] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:20.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:15:0) after 1m0s
[01:55:20.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:15:0)
[01:55:20.070] Timer tick, asking Buildbucket for the build status
[01:55:20.144] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:20.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:16:0) after 1m0s
[01:56:20.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:16:0)
[01:56:20.236] Timer tick, asking Buildbucket for the build status
[01:56:20.295] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:20.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:17:0) after 1m0s
[01:57:20.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:17:0)
[01:57:20.276] Timer tick, asking Buildbucket for the build status
[01:57:20.382] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:20.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:18:0) after 1m0s
[01:58:20.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:18:0)
[01:58:20.446] Timer tick, asking Buildbucket for the build status
[01:58:21.096] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:21.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:19:0) after 1m0s
[01:59:21.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:19:0)
[01:59:21.155] Timer tick, asking Buildbucket for the build status
[01:59:21.275] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:21.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:20:0) after 1m0s
[02:00:21.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:20:0)
[02:00:21.296] Timer tick, asking Buildbucket for the build status
[02:00:21.798] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:21:0) after 1m0s
[02:01:21.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:21:0)
[02:01:21.891] Timer tick, asking Buildbucket for the build status
[02:01:22.411] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:22.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:22:0) after 1m0s
[02:02:22.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:22:0)
[02:02:22.471] Timer tick, asking Buildbucket for the build status
[02:02:22.612] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:22.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:23:0) after 1m0s
[02:03:22.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:23:0)
[02:03:22.632] Timer tick, asking Buildbucket for the build status
[02:03:22.788] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:22.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:24:0) after 1m0s
[02:04:22.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:24:0)
[02:04:22.812] Timer tick, asking Buildbucket for the build status
[02:04:22.948] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:22.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:25:0) after 1m0s
[02:05:22.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:25:0)
[02:05:22.925] Timer tick, asking Buildbucket for the build status
[02:05:23.329] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:23.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:26:0) after 1m0s
[02:06:23.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:26:0)
[02:06:23.416] Timer tick, asking Buildbucket for the build status
[02:06:23.541] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:23.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:27:0) after 1m0s
[02:07:23.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:27:0)
[02:07:23.629] Timer tick, asking Buildbucket for the build status
[02:07:23.755] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:28:0) after 1m0s
[02:08:23.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:28:0)
[02:08:23.846] Timer tick, asking Buildbucket for the build status
[02:08:23.996] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:29:0) after 1m0s
[02:09:23.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:29:0)
[02:09:23.976] Timer tick, asking Buildbucket for the build status
[02:09:24.099] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:24.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:30:0) after 1m0s
[02:10:24.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:30:0)
[02:10:24.140] Timer tick, asking Buildbucket for the build status
[02:10:24.289] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:24.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:31:0) after 1m0s
[02:11:24.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:31:0)
[02:11:24.330] Timer tick, asking Buildbucket for the build status
[02:11:24.467] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:24.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:32:0) after 1m0s
[02:12:24.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:32:0)
[02:12:24.495] Timer tick, asking Buildbucket for the build status
[02:12:24.614] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:33:0) after 1m0s
[02:13:24.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:33:0)
[02:13:24.766] Timer tick, asking Buildbucket for the build status
[02:13:24.844] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:24.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:34:0) after 1m0s
[02:14:24.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:34:0)
[02:14:24.922] Timer tick, asking Buildbucket for the build status
[02:14:25.209] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:25.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:35:0) after 1m0s
[02:15:25.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:35:0)
[02:15:25.232] Timer tick, asking Buildbucket for the build status
[02:15:25.300] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:36:0) after 1m0s
[02:16:25.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:36:0)
[02:16:25.421] Timer tick, asking Buildbucket for the build status
[02:16:25.507] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:25.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:37:0) after 1m0s
[02:17:25.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:37:0)
[02:17:25.526] Timer tick, asking Buildbucket for the build status
[02:17:26.216] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:26.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:38:0) after 1m0s
[02:18:26.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:38:0)
[02:18:26.336] Timer tick, asking Buildbucket for the build status
[02:18:26.538] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:26.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:39:0) after 1m0s
[02:19:26.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:39:0)
[02:19:26.629] Timer tick, asking Buildbucket for the build status
[02:19:26.738] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:26.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:40:0) after 1m0s
[02:20:26.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:40:0)
[02:20:26.759] Timer tick, asking Buildbucket for the build status
[02:20:26.832] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:41:0) after 1m0s
[02:21:26.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:41:0)
[02:21:26.878] Timer tick, asking Buildbucket for the build status
[02:21:26.992] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:26.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:42:0) after 1m0s
[02:22:27.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:42:0)
[02:22:27.051] Timer tick, asking Buildbucket for the build status
[02:22:27.441] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:27.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:43:0) after 1m0s
[02:23:27.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:43:0)
[02:23:27.462] Timer tick, asking Buildbucket for the build status
[02:23:27.569] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:27.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:44:0) after 1m0s
[02:24:27.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:44:0)
[02:24:27.610] Timer tick, asking Buildbucket for the build status
[02:24:27.670] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:27.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:45:0) after 1m0s
[02:25:27.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:45:0)
[02:25:27.696] Timer tick, asking Buildbucket for the build status
[02:25:28.150] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:28.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:46:0) after 1m0s
[02:26:28.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:46:0)
[02:26:28.173] Timer tick, asking Buildbucket for the build status
[02:26:28.284] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:28.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:47:0) after 1m0s
[02:27:28.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:47:0)
[02:27:28.326] Timer tick, asking Buildbucket for the build status
[02:27:28.427] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:28.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:48:0) after 1m0s
[02:28:28.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:48:0)
[02:28:28.490] Timer tick, asking Buildbucket for the build status
[02:28:28.971] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:49:0) after 1m0s
[02:29:29.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:49:0)
[02:29:29.009] Timer tick, asking Buildbucket for the build status
[02:29:29.124] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:29.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:50:0) after 1m0s
[02:30:29.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:50:0)
[02:30:29.206] Timer tick, asking Buildbucket for the build status
[02:30:30.451] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:30.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:51:0) after 1m0s
[02:31:30.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:51:0)
[02:31:30.482] Timer tick, asking Buildbucket for the build status
[02:31:30.598] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:52:0) after 1m0s
[02:32:30.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:52:0)
[02:32:30.603] Timer tick, asking Buildbucket for the build status
[02:32:30.722] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:30.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:53:0) after 1m0s
[02:33:30.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:53:0)
[02:33:30.859] Timer tick, asking Buildbucket for the build status
[02:33:31.073] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:31.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:54:0) after 1m0s
[02:34:31.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:54:0)
[02:34:31.222] Timer tick, asking Buildbucket for the build status
[02:34:31.355] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:31.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:55:0) after 1m0s
[02:35:31.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:55:0)
[02:35:31.433] Timer tick, asking Buildbucket for the build status
[02:35:31.492] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:31.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:56:0) after 1m0s
[02:36:32.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:56:0)
[02:36:32.042] Timer tick, asking Buildbucket for the build status
[02:36:32.109] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:32.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:57:0) after 1m0s
[02:37:32.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:57:0)
[02:37:32.174] Timer tick, asking Buildbucket for the build status
[02:37:32.569] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:32.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:58:0) after 1m0s
[02:38:32.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:58:0)
[02:38:32.774] Timer tick, asking Buildbucket for the build status
[02:38:32.846] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:32.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:59:0) after 1m0s
[02:39:32.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:59:0)
[02:39:32.818] Timer tick, asking Buildbucket for the build status
[02:39:33.085] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:33.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:60:0) after 1m0s
[02:40:33.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:60:0)
[02:40:33.139] Timer tick, asking Buildbucket for the build status
[02:40:33.417] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:33.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:61:0) after 1m0s
[02:41:33.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:61:0)
[02:41:33.527] Timer tick, asking Buildbucket for the build status
[02:41:33.664] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:33.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:62:0) after 1m0s
[02:42:33.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:62:0)
[02:42:33.812] Timer tick, asking Buildbucket for the build status
[02:42:34.163] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:34.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:63:0) after 1m0s
[02:43:34.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:63:0)
[02:43:34.336] Timer tick, asking Buildbucket for the build status
[02:43:34.454] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:34.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:64:0) after 1m0s
[02:44:34.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:64:0)
[02:44:34.697] Timer tick, asking Buildbucket for the build status
[02:44:35.046] Build 8862427289925500736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:35.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027857351930122576:65:0) after 1m0s
[02:44:40.230] Received PubSub notification, asking Buildbucket for the build status
[02:44:43.264] Build 8862427289925500736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:44:43.264] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606531478969194",
"created_by": "project:chromium",
"created_ts": "1606527734788640",
"failure_reason": "BUILD_FAILURE",
"id": "8862427289925500736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b265a247dffc48324cd127528a9b36cd2b8329d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"8b265a247dffc48324cd127528a9b36cd2b8329d\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15897, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"59aa1ef1975ae2d263da864f111eaaa7cdb4f9f2\", \"got_dawn_revision\": \"d88a86c2061da58f21f1b654a5af668f340662a7\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"8b265a247dffc48324cd127528a9b36cd2b8329d\", \"got_revision_cp\": \"refs/heads/master@{#831661}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"8b265a247dffc48324cd127528a9b36cd2b8329d\", \"swarm_hashes_refs/heads/master(at){#831661}_without_patch\": {\"absl_hardening_tests\": \"9509b936e1e93db1fce16ea3976e7b806fa31537\", \"accessibility_unittests\": \"883453591fcd51fffd186f4284d7330c4c63af44\", \"angle_unittests\": \"dcdf3196b9f347ab7ae5d8cd94f00f107010f3fc\", \"app_shell_unittests\": \"37fe7eb146f04a438fd098f51e95fc8dfb7d9276\", \"aura_unittests\": \"59961f1dc02aa384119e01c4fdc4dca4091b7b87\", \"base_unittests\": \"5a01b95113ab79435dbfddcab08da0c597f9d643\", \"base_util_unittests\": \"e1edb8c892de0e4d36b3c3c2f659ff90d64b953e\", \"blink_common_unittests\": \"3b5e889abcf737d7bfbdff55f17e434183c869fc\", \"blink_fuzzer_unittests\": \"987bb8255887c6d6c17dab9661e9a5af94af17c8\", \"blink_heap_unittests\": \"27a7093ac4d709283bfa66db049e9858992a23a4\", \"blink_platform_unittests\": \"57adaa40adc5de5d9fa0043ba8b37abf94a39042\", \"blink_unittests\": \"2a95a3dfe05ff44989c1c81c7787421b9be23bc6\", \"boringssl_crypto_tests\": \"5ed56e4294391e789e6334bd8b4ef89d7801bb84\", \"boringssl_ssl_tests\": \"a27883cf305210b0c6c718c7620aa55d333fe9b6\", \"browser_switcher_bho_unittests\": \"aa682e26dbaf4ea03d16a9b39852f1951574bd4a\", \"browser_tests\": \"2befb865c672d60b4f4a076520280a158425a0f7\", \"capture_unittests\": \"3eb3d2ce3f10478f6bca9685a7373c7e13651539\", \"cast_unittests\": \"64cae03fdc1d0e9e8be23dee64b493a2f1b1403b\", \"cc_unittests\": \"11103f41fdc7f7c4b065e068ff61279d62ab9153\", \"chrome_app_unittests\": \"997c3678ce0db5a99441ec331d80aa4ed780b6ad\", \"chrome_cleaner_unittests\": \"c923b1dd381df65b2b11f85a0a0ce2add833c729\", \"chrome_elf_unittests\": \"987014fbd5bb055fa24dfd3b128d2633c0911d24\", \"chromedriver_unittests\": \"5dd5a84933c2f87b2b2958c69ccf72b21f5aa131\", \"components_browsertests\": \"2b07f1d22ab4e0e5e9f1f389e0d3e02215494d10\", \"components_unittests\": \"aed6d04e60954c8433b22cf26292ce17fe71b05a\", \"compositor_unittests\": \"bb961199ca46a16c7a07b510aa2cbcef1219058b\", \"content_browsertests\": \"a217aa431e608c14e92faf9e0f7f51d16490a624\", \"content_unittests\": \"486f2aef868bfb7b1f4735deb6f57901d554f9d5\", \"courgette_unittests\": \"da8dcd05044e7e94cf2f9c858ac5f00f0abd6e74\", \"crashpad_tests\": \"4231be2777e5527e07ac488ad2db6c4b31d13bb9\", \"cronet_tests\": \"94bd7b923903690737a258fef66184cd75fe37e0\", \"cronet_unittests\": \"7751056232f00b913c7a8c6540291d4b685d5ea2\", \"crypto_unittests\": \"9e3e9ea2685b65b9eb88cbd73754461955da9d30\", \"delayloads_unittests\": \"6a1921635d4841c06a08b60795ee9ccc0b79b0db\", \"device_unittests\": \"f5a697b0dd74b8d2c7707e5a07f0bc7dff64866a\", \"display_unittests\": \"9f536772f624e335f37031588835211a35877aa4\", \"elevation_service_unittests\": \"ad7be2042268ad744db1804b19bfd2d17edfdf01\", \"events_unittests\": \"4883fc3ebf4d6ad325c8b5c7fffffd2a1c64fd54\", \"extensions_browsertests\": \"fe44b1712821aa51b7b5ed2014b4732394afa2d4\", \"extensions_unittests\": \"b51d8886fee0a8599a34aa27783e3cef1e3d5ad2\", \"filesystem_service_unittests\": \"c74aabc5cf8b91ea1c7c50b05e3a3822db0fb0db\", \"gcm_unit_tests\": \"b674846138ee30ab5a24fae2bb32882c4ba7e035\", \"gcp_unittests\": \"bc2d361646d54be316b2b5541e34f2294b1688f7\", \"gfx_unittests\": \"94ccaab43fe2f30f8324374b48ed2639d93a132a\", \"gin_unittests\": \"49c893b177577058993259734c659e4e8e27065a\", \"google_apis_unittests\": \"e5b4ac45b0f93184168e30799c310fb672c5d2bf\", \"gpu_unittests\": \"7bba039058e81602d9dcfaa9673a031b1b9e8cf9\", \"gwp_asan_unittests\": \"8e1650ed78e7489fc10981a41cfb88d6d0553c0d\", \"headless_browsertests\": \"caae8cf57edd1b392c291021e92d2e7144dc8923\", \"headless_unittests\": \"7ab65095299d952c01478940d9857c5a7399fd67\", \"install_static_unittests\": \"2cea63a14149a580deea62da5e6497b168fe1c55\", \"installer_util_unittests\": \"69648b22670cd41cb39d255235f7155c6ea56f2d\", \"interactive_ui_tests\": \"50fa1bcaae61fa3b08ac5873cbb2ae3c57a68bbd\", \"ipc_tests\": \"e1c9c3303b094c4f50d67d7abed987a2fbccba1f\", \"jingle_unittests\": \"16fb542ea52e1f79797a46eeecd9ec0e9bcc383e\", \"latency_unittests\": \"c8f326f1c891bb1a87294a918039466b849e6382\", \"libjingle_xmpp_unittests\": \"e94ecbfca99448bdb459f44e8cd7d438a5898f71\", \"liburlpattern_unittests\": \"8becd94e286821bb962ffb78b9bd9710d57e7d26\", \"media_blink_unittests\": \"65d02d008fd44aa6e149188cc42d50bc7983936f\", \"media_unittests\": \"56f9b6422dabac2377330c3a84ab17782f3fc8be\", \"message_center_unittests\": \"36f027103d05d08ae2d6ef85f4d6c83ee944f2db\", \"midi_unittests\": \"236571f082a386b17596329707f3a612b61df1cc\", \"mojo_core_unittests\": \"eab4948b2421d413e680f349c9c1c29333dfae06\", \"mojo_unittests\": \"5133b64b8829bb49523164d97d0bb22d5eceacca\", \"nacl_loader_unittests\": \"2956eeb16ba8a1f4b76925d447fff0c78d5a117f\", \"native_theme_unittests\": \"bc7aff76a95112bd9dc13cd8c5f689f000769168\", \"net_unittests\": \"bf7972a8141477417ab490df8dd2931a7725096a\", \"notification_helper_unittests\": \"cf0fd2064817c294a5d27d9ff9c751e04f3a4e36\", \"pdf_unittests\": \"65bc2b094ab563231005f9c98a5f36a4bf36a461\", \"perfetto_unittests\": \"70f78ad0db5bbbaf7d9f463b30801e25143462e8\", \"ppapi_unittests\": \"c6c212da75743b7ea6840678fb69a7aedc0af815\", \"printing_unittests\": \"6ff0f047509cabcd1616d88fac360f4dcd2c46b4\", \"remoting_unittests\": \"8c0923873c29e3692681f1f5cc7901a853ecd396\", \"sbox_integration_tests\": \"68c581528953aeda37be48e488fee96c586ecbed\", \"sbox_unittests\": \"22c289e956baad152bdc70ab7664ab33c693f45f\", \"sbox_validation_tests\": \"b416829327ac687f70ff60f4b1a1611db6cf232e\", \"service_manager_unittests\": \"cb32c046d3574f973ff7dd8977e57af7f8037807\", \"services_unittests\": \"641cc64bd6439acd4c6b31e76ff2d134e33071f5\", \"setup_unittests\": \"e9c9759b1c388553f530efa242907cd915d1bc38\", \"shell_dialogs_unittests\": \"aba866fb6976cc8b3ae2862cc63d555a4030a1b1\", \"skia_unittests\": \"1e482f593fbad19001cb60e26f70fb3eb0a82278\", \"snapshot_unittests\": \"1233dd8e1bbba0261a2316591449a0e4b5413553\", \"sql_unittests\": \"7b0b80233e090efbfd19e40755d8efd562140154\", \"storage_unittests\": \"d052878143e282dd6b71788091369b684a1d943a\", \"sync_integration_tests\": \"ea19b27253f53566fe5aa9efaea4aabb5ac591a0\", \"traffic_annotation_auditor_unittests\": \"fa69f52c8f9031dd5259f2a39072e8cede12eb1d\", \"ui_base_unittests\": \"101f65bde44cce14d56aceb4bbc6c1ab17c2c1ea\", \"ui_touch_selection_unittests\": \"265eee8b14ee5e460d6086fbafb15fb036e93568\", \"unit_tests\": \"e879726167d79c31de35400be19012f4b0459d35\", \"updater_tests\": \"401eba4beccb8cd4415c756d0f1a0bd56316f10c\", \"url_unittests\": \"4d2f28e5f33e832ba9417998319ca698f5fc03d9\", \"views_unittests\": \"b1bee3eeedd5606c6ad6d5e014018c3035a523c7\", \"viz_unittests\": \"55cc09ff197eb951beee7cb3234511903690c106\", \"vr_common_unittests\": \"d7502c10190a07116c980ce549dd6bc02db2bd5c\", \"vr_pixeltests\": \"3c491abd6c012d33e494cc1845ca0bc32bbddb91\", \"weblayer_browsertests\": \"9fc8517dbd4386c7417509afa755ce73c04c60ed\", \"weblayer_unittests\": \"0980c1e3396fa0fa46dcf65d8d88d539e7560d4e\", \"wm_unittests\": \"bd11707eef45ab22bbffbd23e357b2d95f20d99d\", \"wtf_unittests\": \"79d502d90ff75d6e5bca13fc221663eb53218f8b\", \"zlib_unittests\": \"074656959fac523a08e63074e3c144b21b39257d\", \"zucchini_unittests\": \"fd55c3644f4239fdc58c5ad73a1ee0f9fa7b528f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-4-qhsy\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606527738504810",
"status": "COMPLETED",
"status_changed_ts": "1606527739039022",
"tags": [
"build_address:luci.chromium.ci/win-asan/15897",
"builder:win-asan",
"buildset:commit/git/8b265a247dffc48324cd127528a9b36cd2b8329d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b265a247dffc48324cd127528a9b36cd2b8329d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027857351930122576",
"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/8862427289925500736/+/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:502555618b656f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606531479301968",
"url": "https://ci.chromium.org/b/8862427289925500736",
"utcnow_ts": "1606531483250369"
}
}
[02:44:43.264] Invocation finished in 1h2m29.772137347s with status FAILED