[05:53:50.267] New invocation is queued and will start shortly
[05:53:51.350] Starting the invocation (attempt 1)
[05:53:51.384] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:53:51.384] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:53:51.384] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023130479578249408",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"70da09c1f7177e51023ed7dae804ef4ba37961b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023130479578249408",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"gitiles_ref:refs/heads/master"
]
}
[05:53:52.045] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611035631485448",
"id": "8857700417638680128",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"70da09c1f7177e51023ed7dae804ef4ba37961b2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611035631912819",
"tags": [
"build_address:luci.chromium.ci/win-asan/16886",
"builder:win-asan",
"buildset:commit/git/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023130479578249408",
"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/8857700417638680128/+/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": "1611035631912935",
"url": "https://ci.chromium.org/b/8857700417638680128",
"utcnow_ts": "1611035632036555"
}
}
[05:53:52.045] Task URL: https://ci.chromium.org/b/8857700417638680128
[05:53:52.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:2:0) after 1m0s
[05:53:54.625] Received PubSub notification, asking Buildbucket for the build status
[05:53:54.688] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:52.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:2:0)
[05:54:52.175] Timer tick, asking Buildbucket for the build status
[05:54:52.343] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:52.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:4:0) after 1m0s
[05:55:52.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:4:0)
[05:55:52.360] Timer tick, asking Buildbucket for the build status
[05:55:52.482] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:52.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:5:0) after 1m0s
[05:56:52.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:5:0)
[05:56:52.502] Timer tick, asking Buildbucket for the build status
[05:56:52.615] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:52.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:6:0) after 1m0s
[05:57:52.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:6:0)
[05:57:52.626] Timer tick, asking Buildbucket for the build status
[05:57:52.760] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:52.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:7:0) after 1m0s
[05:58:52.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:7:0)
[05:58:52.762] Timer tick, asking Buildbucket for the build status
[05:58:53.016] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:53.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:8:0) after 1m0s
[05:59:53.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:8:0)
[05:59:53.049] Timer tick, asking Buildbucket for the build status
[05:59:53.388] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:53.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:9:0) after 1m0s
[06:00:53.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:9:0)
[06:00:53.494] Timer tick, asking Buildbucket for the build status
[06:00:53.775] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:53.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:10:0) after 1m0s
[06:01:53.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:10:0)
[06:01:53.894] Timer tick, asking Buildbucket for the build status
[06:01:54.230] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:54.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:11:0) after 1m0s
[06:02:54.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:11:0)
[06:02:54.249] Timer tick, asking Buildbucket for the build status
[06:02:54.344] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:54.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:12:0) after 1m0s
[06:03:54.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:12:0)
[06:03:54.441] Timer tick, asking Buildbucket for the build status
[06:03:54.571] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:54.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:13:0) after 1m0s
[06:04:54.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:13:0)
[06:04:54.609] Timer tick, asking Buildbucket for the build status
[06:04:54.687] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:54.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:14:0) after 1m0s
[06:05:54.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:14:0)
[06:05:54.705] Timer tick, asking Buildbucket for the build status
[06:05:54.986] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:54.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:15:0) after 1m0s
[06:06:55.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:15:0)
[06:06:55.031] Timer tick, asking Buildbucket for the build status
[06:06:55.529] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:55.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:16:0) after 1m0s
[06:07:55.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:16:0)
[06:07:55.548] Timer tick, asking Buildbucket for the build status
[06:07:55.651] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:55.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:17:0) after 1m0s
[06:08:55.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:17:0)
[06:08:55.679] Timer tick, asking Buildbucket for the build status
[06:08:56.062] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:56.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:18:0) after 1m0s
[06:09:56.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:18:0)
[06:09:56.107] Timer tick, asking Buildbucket for the build status
[06:09:56.294] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:56.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:19:0) after 1m0s
[06:10:56.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:19:0)
[06:10:56.315] Timer tick, asking Buildbucket for the build status
[06:10:56.651] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:56.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:20:0) after 1m0s
[06:11:56.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:20:0)
[06:11:56.723] Timer tick, asking Buildbucket for the build status
[06:11:56.873] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:56.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:21:0) after 1m0s
[06:12:56.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:21:0)
[06:12:56.915] Timer tick, asking Buildbucket for the build status
[06:12:57.013] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:57.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:22:0) after 1m0s
[06:13:57.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:22:0)
[06:13:57.057] Timer tick, asking Buildbucket for the build status
[06:13:57.296] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:57.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:23:0) after 1m0s
[06:14:57.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:23:0)
[06:14:57.308] Timer tick, asking Buildbucket for the build status
[06:14:57.482] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:57.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:24:0) after 1m0s
[06:15:57.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:24:0)
[06:15:57.535] Timer tick, asking Buildbucket for the build status
[06:15:57.665] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:57.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:25:0) after 1m0s
[06:16:57.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:25:0)
[06:16:57.646] Timer tick, asking Buildbucket for the build status
[06:16:57.755] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:57.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:26:0) after 1m0s
[06:17:57.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:26:0)
[06:17:57.783] Timer tick, asking Buildbucket for the build status
[06:17:57.999] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:57.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:27:0) after 1m0s
[06:18:58.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:27:0)
[06:18:58.000] Timer tick, asking Buildbucket for the build status
[06:18:58.164] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:58.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:28:0) after 1m0s
[06:19:58.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:28:0)
[06:19:58.183] Timer tick, asking Buildbucket for the build status
[06:19:58.286] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:58.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:29:0) after 1m0s
[06:20:58.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:29:0)
[06:20:58.306] Timer tick, asking Buildbucket for the build status
[06:20:59.508] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:59.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:30:0) after 1m0s
[06:21:59.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:30:0)
[06:21:59.564] Timer tick, asking Buildbucket for the build status
[06:21:59.910] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:59.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:31:0) after 1m0s
[06:22:59.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:31:0)
[06:22:59.979] Timer tick, asking Buildbucket for the build status
[06:23:00.454] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:00.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:32:0) after 1m0s
[06:24:00.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:32:0)
[06:24:00.506] Timer tick, asking Buildbucket for the build status
[06:24:00.641] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:00.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:33:0) after 1m0s
[06:25:00.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:33:0)
[06:25:00.741] Timer tick, asking Buildbucket for the build status
[06:25:00.878] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:00.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:34:0) after 1m0s
[06:26:01.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:34:0)
[06:26:01.337] Timer tick, asking Buildbucket for the build status
[06:26:01.444] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:01.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:35:0) after 1m0s
[06:27:01.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:35:0)
[06:27:01.822] Timer tick, asking Buildbucket for the build status
[06:27:01.923] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:01.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:36:0) after 1m0s
[06:28:02.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:36:0)
[06:28:02.269] Timer tick, asking Buildbucket for the build status
[06:28:02.797] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:02.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:37:0) after 1m0s
[06:29:02.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:37:0)
[06:29:02.968] Timer tick, asking Buildbucket for the build status
[06:29:03.622] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:03.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:38:0) after 1m0s
[06:30:04.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:38:0)
[06:30:04.217] Timer tick, asking Buildbucket for the build status
[06:30:04.316] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:04.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:39:0) after 1m0s
[06:31:04.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:39:0)
[06:31:04.396] Timer tick, asking Buildbucket for the build status
[06:31:04.837] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:04.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:40:0) after 1m0s
[06:32:05.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:40:0)
[06:32:05.017] Timer tick, asking Buildbucket for the build status
[06:32:05.105] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:05.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:41:0) after 1m0s
[06:33:05.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:41:0)
[06:33:05.168] Timer tick, asking Buildbucket for the build status
[06:33:05.719] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:05.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:42:0) after 1m0s
[06:34:05.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:42:0)
[06:34:05.777] Timer tick, asking Buildbucket for the build status
[06:34:05.973] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:05.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:43:0) after 1m0s
[06:35:06.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:43:0)
[06:35:06.099] Timer tick, asking Buildbucket for the build status
[06:35:06.199] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:06.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:44:0) after 1m0s
[06:36:06.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:44:0)
[06:36:06.256] Timer tick, asking Buildbucket for the build status
[06:36:06.385] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:06.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:45:0) after 1m0s
[06:37:06.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:45:0)
[06:37:06.498] Timer tick, asking Buildbucket for the build status
[06:37:07.519] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:07.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:46:0) after 1m0s
[06:38:07.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:46:0)
[06:38:07.576] Timer tick, asking Buildbucket for the build status
[06:38:07.785] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:07.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:47:0) after 1m0s
[06:39:07.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:47:0)
[06:39:07.816] Timer tick, asking Buildbucket for the build status
[06:39:08.004] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:08.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:48:0) after 1m0s
[06:40:08.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:48:0)
[06:40:08.020] Timer tick, asking Buildbucket for the build status
[06:40:08.406] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:08.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:49:0) after 1m0s
[06:41:08.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:49:0)
[06:41:08.687] Timer tick, asking Buildbucket for the build status
[06:41:08.824] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:08.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:50:0) after 1m0s
[06:42:09.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:50:0)
[06:42:09.047] Timer tick, asking Buildbucket for the build status
[06:42:09.142] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:09.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:51:0) after 1m0s
[06:43:09.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:51:0)
[06:43:09.222] Timer tick, asking Buildbucket for the build status
[06:43:09.302] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:09.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:52:0) after 1m0s
[06:44:09.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:52:0)
[06:44:09.422] Timer tick, asking Buildbucket for the build status
[06:44:09.650] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:53:0) after 1m0s
[06:45:09.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:53:0)
[06:45:09.736] Timer tick, asking Buildbucket for the build status
[06:45:09.906] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:09.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:54:0) after 1m0s
[06:46:09.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:54:0)
[06:46:09.908] Timer tick, asking Buildbucket for the build status
[06:46:10.036] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:10.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:55:0) after 1m0s
[06:47:10.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:55:0)
[06:47:10.128] Timer tick, asking Buildbucket for the build status
[06:47:10.225] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:10.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:56:0) after 1m0s
[06:48:10.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:56:0)
[06:48:10.317] Timer tick, asking Buildbucket for the build status
[06:48:10.748] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:10.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:57:0) after 1m0s
[06:49:10.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:57:0)
[06:49:10.800] Timer tick, asking Buildbucket for the build status
[06:49:10.912] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:10.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:58:0) after 1m0s
[06:50:10.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:58:0)
[06:50:10.934] Timer tick, asking Buildbucket for the build status
[06:50:11.125] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:11.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:59:0) after 1m0s
[06:51:11.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:59:0)
[06:51:11.132] Timer tick, asking Buildbucket for the build status
[06:51:11.321] Build 8857700417638680128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:11.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023130479578249408:60:0) after 1m0s
[06:52:00.697] Received PubSub notification, asking Buildbucket for the build status
[06:52:01.018] Build 8857700417638680128: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:52:01.019] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611039120206645",
"created_by": "project:chromium",
"created_ts": "1611035631485448",
"failure_reason": "BUILD_FAILURE",
"id": "8857700417638680128",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"70da09c1f7177e51023ed7dae804ef4ba37961b2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"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\": \"70da09c1f7177e51023ed7dae804ef4ba37961b2\", \"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\": 16886, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-2\", \"got_angle_revision\": \"81430e11c81e26edc9fd0cf9f6f9f65c8e1011f7\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"70da09c1f7177e51023ed7dae804ef4ba37961b2\", \"got_revision_cp\": \"refs/heads/master@{#844674}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"ee927aaa58e688ad6aa0256b3929c1eeea0bebd5\", \"got_v8_revision_cp\": \"refs/heads/9.0.8@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"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\": \"70da09c1f7177e51023ed7dae804ef4ba37961b2\", \"swarm_hashes_refs/heads/master(at){#844674}_without_patch\": {\"absl_hardening_tests\": \"ebfef63da7acc9e3e703c5bddec9f85f6be79f09\", \"accessibility_unittests\": \"08240ab9380fb611962cf5c94b13ee7f52d538e6\", \"angle_unittests\": \"b70f787db1c82731102bde4ffae78bc55a23cd14\", \"app_shell_unittests\": \"f298b613ffe52d063ceb56641ca9d6458a436284\", \"aura_unittests\": \"d4e328554b919ae2fd21014acde028c759063319\", \"base_unittests\": \"e24ade3910a609d5e8deec23b098616e4ac8ed9e\", \"base_util_unittests\": \"a462d329a543e6a92d7fcf6dceaf1faf34cd2bfe\", \"blink_common_unittests\": \"6093642193ae2183a3c3f493a0d4647b0a6c8cd0\", \"blink_fuzzer_unittests\": \"ab975891e18bef3cdf4fe34bfa17e109cea2263c\", \"blink_heap_unittests\": \"563dc1f3175821e0433f87f59a048f25e6c797cd\", \"blink_platform_unittests\": \"5e28a7b7f261ac6a1db3d16ef74acecb5527ece2\", \"blink_unittests\": \"7e322626a14a2989756a4abeaba38124c6994686\", \"boringssl_crypto_tests\": \"d2a447f10f5e3f52a244c454d67043fe89c342dc\", \"boringssl_ssl_tests\": \"cfe3379698f021f6eaf1b17818fb78585246ab8a\", \"browser_switcher_bho_unittests\": \"90d791d55a314ff4959a27d49625a9b3ab38ec63\", \"browser_tests\": \"ffa5249d4a346d2b76e03e888a436b29818d903d\", \"capture_unittests\": \"2835121d5aecfe329c6baef909f8540138cdab98\", \"cast_unittests\": \"2878de20f7b2a857f62897ffbbc880faa6e93ce5\", \"cc_unittests\": \"ae64649af7b0f9cd9722b8e2073f554745763a89\", \"chrome_app_unittests\": \"776236057f8eace4d05d1dd616544781582b2aaa\", \"chrome_cleaner_unittests\": \"42e4b11d3a2418c734eb5addadb9f3f383aca688\", \"chrome_elf_unittests\": \"b269eca3abf47d13a7c0a273f79b00905caa44c9\", \"chromedriver_unittests\": \"eeaa18ab1c017bee5d9bf38f934236c1f4438df7\", \"components_browsertests\": \"ffd25a982e56b9e27ace0653ebf153ba064b0881\", \"components_unittests\": \"a87c6b4591de6a0d21eea32c4d831f1a79f3895a\", \"compositor_unittests\": \"750e5ed8ba371dd372d0abf6e86c16cb8b19ffe2\", \"content_browsertests\": \"261063d4c46eb9c6f9927373f52622ba16c4192d\", \"content_unittests\": \"34fbe460446dfeddfe216a1d4e53a86d20329dcc\", \"courgette_unittests\": \"5e4d6453fb3b338f3b2d99f6d013538578f95ed2\", \"crashpad_tests\": \"1d9fe6da264ef4f997ecc5f0ef85426ec3bdc073\", \"cronet_tests\": \"5899a790c261a3e14955858dc709fc1618d6ccc3\", \"cronet_unittests\": \"708533885bf7745d492f81bab895bd318d4cd166\", \"crypto_unittests\": \"7f8137edf4dce3625cd62557beb93484eb10a521\", \"delayloads_unittests\": \"fc611c853bd922334d2827b31f628bbfe2c2dfe8\", \"device_unittests\": \"d0c1627a5a89f8ec1e4fdab1af0ed8597e36df17\", \"display_unittests\": \"280fb6d1f1c4303a80c20b9404ab363b6168c484\", \"elevation_service_unittests\": \"ff50055bddb2ec580e5008053ef65afd1ed47908\", \"events_unittests\": \"f4c49e985a924c84b98027b9b1e694cdc39d1fde\", \"extensions_browsertests\": \"5207cec527bc44946ea28dcee826e7bc74915c9f\", \"extensions_unittests\": \"7d9b177aaa260756ea90a410ec5725f140417baa\", \"filesystem_service_unittests\": \"2eb3f67104df216b5d9bdd4000f450d00b2d3524\", \"gcm_unit_tests\": \"6834186ca7768460ddf5aefd3c1f59e2dc5df86b\", \"gcp_unittests\": \"ce15bca736142beb7027c034b08fe3b032d8038a\", \"gfx_unittests\": \"5ed123eb1122b6d38671f0e83e073b8eb6328cd3\", \"gin_unittests\": \"e2a1942615849e70001f4c3b990e587cbef67656\", \"google_apis_unittests\": \"12d1020ac2a6f052663a96994c9555b8023010f4\", \"gpu_unittests\": \"d5e85929f1cc38a34adef4f071ca8838f2f396b0\", \"gwp_asan_unittests\": \"4e0065d1d3388c9198d76ec7432a1768514d776e\", \"headless_browsertests\": \"60bc0e7a688b7538ab87b9fc938d4f4e39f72b60\", \"headless_unittests\": \"0c69ed7d63d59267808c8c2caf354f278c1d3b7f\", \"install_static_unittests\": \"5742a47686c22ca1bc1108cc62a14428c9cebd11\", \"installer_util_unittests\": \"f82df95d12d44864e018c027bad02f9de6330aa6\", \"interactive_ui_tests\": \"25270070a1e680828f9e07ddb0992d7d25f7088d\", \"ipc_tests\": \"880e62e50159b1b7e3d36a7d3111cbc323ffcb70\", \"jingle_unittests\": \"a76ececcd4e20964865293e5d92f57de4fabeb40\", \"latency_unittests\": \"17a6acc7d0d08f9376007a80c282d0fa2e7533fb\", \"libjingle_xmpp_unittests\": \"b7db71bfa5bd4c75fcc7df17ed97cba3a89ced9a\", \"liburlpattern_unittests\": \"699759f09985b2d557af1ee7ce85bd2a0cdffc00\", \"media_blink_unittests\": \"8f211a6f0dd1338ea8247505c01dd5f9d104b709\", \"media_unittests\": \"b6b7076b4a2fb20b2fcad7d7f69a2db92ae28096\", \"message_center_unittests\": \"6445f57eda0bcc975ff9598c3d1dd5a085851477\", \"midi_unittests\": \"209de91f90f79808fda664f24d8e87706298a49e\", \"mojo_core_unittests\": \"bd9b9ba70b7260cfca8b696c77e7bae32a232342\", \"mojo_unittests\": \"3cc53b9b44891648c2fa75bcc7d1b19f7148e21b\", \"nacl_loader_unittests\": \"a536205fa48dc4d9ec332c56d24c51677d1798ba\", \"native_theme_unittests\": \"c32b31c52e8e4617816a0c9916c162249b8aa344\", \"net_unittests\": \"ca15341b27ea899c98c131b77b51e23e3d6579e3\", \"notification_helper_unittests\": \"bdc820bb8d89414814a51750669034806f1b8862\", \"pdf_unittests\": \"065927b74cc49308d237f8642264027deb6deac3\", \"perfetto_unittests\": \"f08c96f4694893eae621b80abc55a4c6e7d3f1c0\", \"ppapi_unittests\": \"22478db02bcdfb54e81eac6a954d5ade6726df77\", \"printing_unittests\": \"1798445c46bf0e9458121103b83760d73463f36c\", \"remoting_unittests\": \"3d0819e35e9682c1c01eba7d2e8eceabb6bc6765\", \"sbox_integration_tests\": \"bed360da394bf45b51aa9969a56f669331c62289\", \"sbox_unittests\": \"7fc8a80df0be42ada78ce8e2d8b32a15d9e45c12\", \"sbox_validation_tests\": \"521932972d2fe2c42b1662f22dfb588080b1ebd5\", \"service_manager_unittests\": \"b91f43950a3610cd5743ad3c34a23095f426350e\", \"services_unittests\": \"69bd6bd6822b7dd6eff23ad217024489d4dbe561\", \"setup_unittests\": \"f59226b2eede989b841379af69b0c92cefd533fb\", \"shell_dialogs_unittests\": \"acc4c11c371c2d4a788f7fa733a783abf374b8f2\", \"skia_unittests\": \"bd7ab2e24a4addec2b341c421cae17d371582687\", \"snapshot_unittests\": \"91a3b78f0c1fe988e6361a1e0165cdbe96893359\", \"sql_unittests\": \"39322d3848faac96bfdaac4035eefe88a09a0b46\", \"storage_unittests\": \"2a76173782a376f42d587efcdde2d4149749e0b2\", \"sync_integration_tests\": \"240171c7d670dbed54aaef85c0cef944ae90eb82\", \"traffic_annotation_auditor_unittests\": \"27f7cbeb9cceb3b44ff1bcb5e3935853b9a62cf8\", \"ui_base_unittests\": \"a22b40ba48f17f68df82662bfc3377c7c9bd56e9\", \"ui_touch_selection_unittests\": \"e2ef3737b9b5b0eb25f565e0c5eda9793f4f255e\", \"unit_tests\": \"a02ca4ad4fa3e6714d5a99dc3b4ba778b199ac89\", \"updater_tests\": \"58621c5a122f772f5ad4d21275fc20cd5dbd0a4d\", \"url_unittests\": \"8ecf472720e7beb6a9ba6e77338ff8446a14ea03\", \"views_unittests\": \"37a84cb44baf0b5ac7462e6eb1589aa6b883f8d1\", \"viz_unittests\": \"9593a7f0945eb54a04e41f804d0cfaf513f9ce97\", \"vr_common_unittests\": \"1d3becdc0aa383564156cf174e2350993a19fbb3\", \"vr_pixeltests\": \"323caf195dc986c068344de83c1053b623ef2bb0\", \"weblayer_browsertests\": \"fc9d3a2361e2ab94df0f9809213d1e2ef2a9b41d\", \"weblayer_unittests\": \"07699a755ebfee12aaa13dbf4d3135723d42de9c\", \"wm_unittests\": \"bc31758f2e01ce3ddb39c41e5f1ef343b9eed86a\", \"wtf_unittests\": \"7e74824fbc355d7d44798a9204f5187676d635c9\", \"zlib_unittests\": \"238e463f152dedf6a97cc8a7e9c9c09113900edc\", \"zucchini_unittests\": \"0a14845efc482728a6d8820cac93ff13408402e8\"}}, \"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\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611035634128215",
"status": "COMPLETED",
"status_changed_ts": "1611035634489918",
"tags": [
"build_address:luci.chromium.ci/win-asan/16886",
"builder:win-asan",
"buildset:commit/git/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/70da09c1f7177e51023ed7dae804ef4ba37961b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023130479578249408",
"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/8857700417638680128/+/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:5132066ac6c18c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611039120444411",
"url": "https://ci.chromium.org/b/8857700417638680128",
"utcnow_ts": "1611039120997692"
}
}
[06:52:01.019] Invocation finished in 58m10.767198791s with status FAILED