[02:52:22.109] New invocation is queued and will start shortly
[02:52:23.666] Starting the invocation (attempt 1)
[02:52:23.706] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:52:23.706] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:52:23.707] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024138463275488752",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a0125a06da5b60b2992a124bc2bb7af8122fb0de\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024138463275488752",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"gitiles_ref:refs/heads/master"
]
}
[02:52:24.934] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610074343812777",
"id": "8858708400821415392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0125a06da5b60b2992a124bc2bb7af8122fb0de\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610074344494012",
"tags": [
"build_address:luci.chromium.ci/win-asan/16685",
"builder:win-asan",
"buildset:commit/git/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024138463275488752",
"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/8858708400821415392/+/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": "1610074344494262",
"url": "https://ci.chromium.org/b/8858708400821415392",
"utcnow_ts": "1610074344885030"
}
}
[02:52:24.934] Task URL: https://ci.chromium.org/b/8858708400821415392
[02:52:24.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:2:0) after 1m0s
[02:52:32.128] Received PubSub notification, asking Buildbucket for the build status
[02:52:32.203] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:25.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:2:0)
[02:53:25.184] Timer tick, asking Buildbucket for the build status
[02:53:25.389] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:25.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:4:0) after 1m0s
[02:54:25.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:4:0)
[02:54:25.525] Timer tick, asking Buildbucket for the build status
[02:54:25.653] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:5:0) after 1m0s
[02:55:25.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:5:0)
[02:55:25.808] Timer tick, asking Buildbucket for the build status
[02:55:26.094] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:6:0) after 1m0s
[02:56:26.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:6:0)
[02:56:26.402] Timer tick, asking Buildbucket for the build status
[02:56:26.493] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:26.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:7:0) after 1m0s
[02:57:26.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:7:0)
[02:57:26.562] Timer tick, asking Buildbucket for the build status
[02:57:26.681] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:26.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:8:0) after 1m0s
[02:58:26.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:8:0)
[02:58:26.881] Timer tick, asking Buildbucket for the build status
[02:58:26.951] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:9:0) after 1m0s
[02:59:27.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:9:0)
[02:59:27.172] Timer tick, asking Buildbucket for the build status
[02:59:27.262] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:27.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:10:0) after 1m0s
[03:00:28.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:10:0)
[03:00:28.052] Timer tick, asking Buildbucket for the build status
[03:00:28.155] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:28.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:11:0) after 1m0s
[03:01:28.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:11:0)
[03:01:28.505] Timer tick, asking Buildbucket for the build status
[03:01:28.574] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:28.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:12:0) after 1m0s
[03:02:28.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:12:0)
[03:02:28.624] Timer tick, asking Buildbucket for the build status
[03:02:28.749] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:28.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:13:0) after 1m0s
[03:03:28.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:13:0)
[03:03:28.810] Timer tick, asking Buildbucket for the build status
[03:03:28.881] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:28.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:14:0) after 1m0s
[03:04:28.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:14:0)
[03:04:28.907] Timer tick, asking Buildbucket for the build status
[03:04:28.987] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:28.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:15:0) after 1m0s
[03:05:29.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:15:0)
[03:05:29.467] Timer tick, asking Buildbucket for the build status
[03:05:29.535] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:16:0) after 1m0s
[03:06:29.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:16:0)
[03:06:29.707] Timer tick, asking Buildbucket for the build status
[03:06:29.782] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:29.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:17:0) after 1m0s
[03:07:30.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:17:0)
[03:07:30.045] Timer tick, asking Buildbucket for the build status
[03:07:30.142] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:30.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:18:0) after 1m0s
[03:08:30.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:18:0)
[03:08:30.288] Timer tick, asking Buildbucket for the build status
[03:08:30.415] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:19:0) after 1m0s
[03:09:30.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:19:0)
[03:09:30.716] Timer tick, asking Buildbucket for the build status
[03:09:30.806] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:30.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:20:0) after 1m0s
[03:10:31.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:20:0)
[03:10:31.093] Timer tick, asking Buildbucket for the build status
[03:10:31.808] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:31.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:21:0) after 1m0s
[03:11:32.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:21:0)
[03:11:32.166] Timer tick, asking Buildbucket for the build status
[03:11:32.758] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:32.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:22:0) after 1m0s
[03:12:32.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:22:0)
[03:12:32.953] Timer tick, asking Buildbucket for the build status
[03:12:33.143] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:33.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:23:0) after 1m0s
[03:13:33.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:23:0)
[03:13:33.282] Timer tick, asking Buildbucket for the build status
[03:13:33.398] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:33.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:24:0) after 1m0s
[03:14:33.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:24:0)
[03:14:33.549] Timer tick, asking Buildbucket for the build status
[03:14:33.833] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:25:0) after 1m0s
[03:15:33.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:25:0)
[03:15:33.912] Timer tick, asking Buildbucket for the build status
[03:15:34.072] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:34.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:26:0) after 1m0s
[03:16:34.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:26:0)
[03:16:34.218] Timer tick, asking Buildbucket for the build status
[03:16:34.442] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:34.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:27:0) after 1m0s
[03:17:34.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:27:0)
[03:17:34.522] Timer tick, asking Buildbucket for the build status
[03:17:34.683] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:34.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:28:0) after 1m0s
[03:18:34.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:28:0)
[03:18:34.757] Timer tick, asking Buildbucket for the build status
[03:18:35.797] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:35.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:29:0) after 1m0s
[03:19:35.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:29:0)
[03:19:35.866] Timer tick, asking Buildbucket for the build status
[03:19:36.252] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:36.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:30:0) after 1m0s
[03:20:36.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:30:0)
[03:20:36.372] Timer tick, asking Buildbucket for the build status
[03:20:36.564] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:36.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:31:0) after 1m0s
[03:21:36.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:31:0)
[03:21:36.626] Timer tick, asking Buildbucket for the build status
[03:21:37.016] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:37.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:32:0) after 1m0s
[03:22:37.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:32:0)
[03:22:37.053] Timer tick, asking Buildbucket for the build status
[03:22:37.256] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:37.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:33:0) after 1m0s
[03:23:37.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:33:0)
[03:23:37.237] Timer tick, asking Buildbucket for the build status
[03:23:38.028] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:38.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:34:0) after 1m0s
[03:24:38.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:34:0)
[03:24:38.068] Timer tick, asking Buildbucket for the build status
[03:24:38.176] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:38.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:35:0) after 1m0s
[03:25:38.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:35:0)
[03:25:38.214] Timer tick, asking Buildbucket for the build status
[03:25:38.427] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:38.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:36:0) after 1m0s
[03:26:38.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:36:0)
[03:26:38.480] Timer tick, asking Buildbucket for the build status
[03:26:38.699] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:38.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:37:0) after 1m0s
[03:27:38.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:37:0)
[03:27:38.742] Timer tick, asking Buildbucket for the build status
[03:27:38.864] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:38.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:38:0) after 1m0s
[03:28:38.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:38:0)
[03:28:38.877] Timer tick, asking Buildbucket for the build status
[03:28:39.022] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:39.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:39:0) after 1m0s
[03:29:39.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:39:0)
[03:29:39.010] Timer tick, asking Buildbucket for the build status
[03:29:39.085] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:39.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:40:0) after 1m0s
[03:30:39.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:40:0)
[03:30:39.139] Timer tick, asking Buildbucket for the build status
[03:30:39.217] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:39.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:41:0) after 1m0s
[03:31:39.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:41:0)
[03:31:39.238] Timer tick, asking Buildbucket for the build status
[03:31:39.313] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:39.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:42:0) after 1m0s
[03:32:39.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:42:0)
[03:32:39.362] Timer tick, asking Buildbucket for the build status
[03:32:39.520] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:39.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:43:0) after 1m0s
[03:33:39.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:43:0)
[03:33:39.561] Timer tick, asking Buildbucket for the build status
[03:33:39.728] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:39.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:44:0) after 1m0s
[03:34:39.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:44:0)
[03:34:39.748] Timer tick, asking Buildbucket for the build status
[03:34:39.857] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:39.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:45:0) after 1m0s
[03:35:39.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:45:0)
[03:35:39.960] Timer tick, asking Buildbucket for the build status
[03:35:40.130] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:40.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:46:0) after 1m0s
[03:36:40.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:46:0)
[03:36:40.184] Timer tick, asking Buildbucket for the build status
[03:36:40.947] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:40.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:47:0) after 1m0s
[03:37:40.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:47:0)
[03:37:40.995] Timer tick, asking Buildbucket for the build status
[03:37:41.125] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:41.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:48:0) after 1m0s
[03:38:41.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:48:0)
[03:38:41.147] Timer tick, asking Buildbucket for the build status
[03:38:41.314] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:41.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:49:0) after 1m0s
[03:39:41.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:49:0)
[03:39:41.338] Timer tick, asking Buildbucket for the build status
[03:39:41.412] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:41.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:50:0) after 1m0s
[03:40:41.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:50:0)
[03:40:41.431] Timer tick, asking Buildbucket for the build status
[03:40:41.565] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:41.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:51:0) after 1m0s
[03:41:41.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:51:0)
[03:41:41.628] Timer tick, asking Buildbucket for the build status
[03:41:41.790] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:41.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:52:0) after 1m0s
[03:42:41.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:52:0)
[03:42:41.884] Timer tick, asking Buildbucket for the build status
[03:42:42.066] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:42.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:53:0) after 1m0s
[03:43:42.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:53:0)
[03:43:42.166] Timer tick, asking Buildbucket for the build status
[03:43:42.451] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:42.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:54:0) after 1m0s
[03:44:42.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:54:0)
[03:44:42.485] Timer tick, asking Buildbucket for the build status
[03:44:42.580] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:42.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:55:0) after 1m0s
[03:45:42.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:55:0)
[03:45:42.656] Timer tick, asking Buildbucket for the build status
[03:45:42.799] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:42.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:56:0) after 1m0s
[03:46:42.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:56:0)
[03:46:42.869] Timer tick, asking Buildbucket for the build status
[03:46:42.934] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:42.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:57:0) after 1m0s
[03:47:42.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:57:0)
[03:47:42.960] Timer tick, asking Buildbucket for the build status
[03:47:43.064] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:43.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:58:0) after 1m0s
[03:48:43.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:58:0)
[03:48:43.099] Timer tick, asking Buildbucket for the build status
[03:48:43.163] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:43.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:59:0) after 1m0s
[03:49:43.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:59:0)
[03:49:43.210] Timer tick, asking Buildbucket for the build status
[03:49:43.299] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:43.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:60:0) after 1m0s
[03:50:43.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:60:0)
[03:50:43.358] Timer tick, asking Buildbucket for the build status
[03:50:43.466] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:43.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:61:0) after 1m0s
[03:51:43.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:61:0)
[03:51:43.492] Timer tick, asking Buildbucket for the build status
[03:51:43.640] Build 8858708400821415392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:43.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024138463275488752:62:0) after 1m0s
[03:52:38.496] Received PubSub notification, asking Buildbucket for the build status
[03:52:38.825] Build 8858708400821415392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:52:38.825] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610077956325528",
"created_by": "project:chromium",
"created_ts": "1610074343812777",
"id": "8858708400821415392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0125a06da5b60b2992a124bc2bb7af8122fb0de\"}}",
"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\": \"a0125a06da5b60b2992a124bc2bb7af8122fb0de\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16685, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"a7158eb524ddc3015c54340d3c0593d6641d41ad\", \"got_dawn_revision\": \"00fcab636a055c78036ee20bb1a89339b0a28bad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a0125a06da5b60b2992a124bc2bb7af8122fb0de\", \"got_revision_cp\": \"refs/heads/master@{#841331}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"937395c1ed5d20af5a5fd999d7e2deddf1b78fe3\", \"got_v8_revision\": \"d87c3006482190728da53f87875b2a528dfb7ef3\", \"got_v8_revision_cp\": \"refs/heads/8.9.217@{#1}\", \"got_webrtc_revision\": \"cdb3bc3b93dae5237610d0e45bccd900812a0cfe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32922}\", \"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\": \"a0125a06da5b60b2992a124bc2bb7af8122fb0de\", \"swarm_hashes_refs/heads/master(at){#841331}_without_patch\": {\"absl_hardening_tests\": \"b11aab489fd338eec5039b0f0561868ec69583e4\", \"accessibility_unittests\": \"599fabafe7b017791290f6c5c7d024b407605e79\", \"angle_unittests\": \"7ceb9fd5527afd0b2d7a36d2eb9be2842afb38ae\", \"app_shell_unittests\": \"7eb6c66036420d55967f3c26906206675ce8b30a\", \"aura_unittests\": \"9a63377df69a967366f8efad7a2b96572bb568b0\", \"base_unittests\": \"a998a25a8c27688b7d1b12fbb042d7f479cc99a5\", \"base_util_unittests\": \"8a745bc9b33fb71968fd99a09cfbc631642e1247\", \"blink_common_unittests\": \"82ee4c42b2a9656597d503644b1a445a49f5b58b\", \"blink_fuzzer_unittests\": \"15c5230bf43cb866fd2cfe4496c53065be3b951d\", \"blink_heap_unittests\": \"e72b164345bf0f8839c735519c8feaee30b3a05a\", \"blink_platform_unittests\": \"117f190f62293675d4d6ae70aac61164258b7c60\", \"blink_unittests\": \"0e34f608c9c23203b451f48251fc63e88fb828df\", \"boringssl_crypto_tests\": \"e5ea2633a84299abf0b88ce4806ee95a00a938cc\", \"boringssl_ssl_tests\": \"b1c7a85dd41d3271dc059e1f66852df1e2f18eea\", \"browser_switcher_bho_unittests\": \"c51c77c6a0b091a07eb860d1f0c791d72565a76d\", \"browser_tests\": \"eb1e6c0ca0b7b48cdfa73e889a06326182ba5058\", \"capture_unittests\": \"09ed9bb45f22f9933db0ed6bfac9c2770a121acc\", \"cast_unittests\": \"f78eacfed80700c155be0e49ecf843a28a72c4e8\", \"cc_unittests\": \"cbe44ed7e124729c961e076194517fd8545ffe32\", \"chrome_app_unittests\": \"c436c322f54b6a607efd56d9b38c8bf78ef1e70e\", \"chrome_cleaner_unittests\": \"d46f9adc423b637d6583c2d43ee51647041a84c4\", \"chrome_elf_unittests\": \"244922d5b1e9c198439d7d5b3957244471d58a74\", \"chromedriver_unittests\": \"8cebcae71fd598e46048c07370b6ff7ffb6d84bf\", \"components_browsertests\": \"11b07a8575e39261decf3b15a2848ab1e962a066\", \"components_unittests\": \"9362826f0120c47df969f0af8623e8a3ffdd8cf0\", \"compositor_unittests\": \"2b9dfa02cc7e977e2687f49b93948f38584fbc1d\", \"content_browsertests\": \"4ae27a0c18e52060a96564427395896307ba19f8\", \"content_unittests\": \"904b8c94e3b3b7b3c4ad513a50a5cdfa6e64d33a\", \"courgette_unittests\": \"9f12be1e84d597d8ec026823513729cc8ab18f88\", \"crashpad_tests\": \"727093d32309c8707e0d01878d6cb232173f1afe\", \"cronet_tests\": \"772e3d11114e8ffe6d3f890e849d305444055c35\", \"cronet_unittests\": \"a556b536790f386e698e43557c23f4c6032eab94\", \"crypto_unittests\": \"4db24f0aaeb20356f9ea203bed36cd90fcd3a8ac\", \"delayloads_unittests\": \"e977c4f63db2e0d9e4351f8b4024fd4c0b6b8275\", \"device_unittests\": \"d1c09768ddc5a75277c6bcd85aaa2bb6456199c5\", \"display_unittests\": \"b457be4e4125b9fdf29a8da40a59446f56d2ae9a\", \"elevation_service_unittests\": \"9f217037d410f21a7a8e034e112b1db8e67c1cb7\", \"events_unittests\": \"235a7ccb98a8582b6f1aac00121f2889939df612\", \"extensions_browsertests\": \"675267c796f5b6db0eab1c9bda4fcabeb6e93266\", \"extensions_unittests\": \"bc5b5c276729c30e652bb9718335bcfad3f89148\", \"filesystem_service_unittests\": \"319eb8ac09d2934b9980c8b1c19f459ab54b18a5\", \"gcm_unit_tests\": \"a4e4e57b3d4a72732b2a8d4441d4354f17dae905\", \"gcp_unittests\": \"4fa1e7adb108d8367a9451b3461c1c4d67c2f995\", \"gfx_unittests\": \"05e6a238de6e209bfaf3d30d76e514f8cefc94eb\", \"gin_unittests\": \"4f10c6dab137bcac74d8f31cb7f7a8f62e60bcdc\", \"google_apis_unittests\": \"cec2bfa9342f9a2617508376d498d9ca6a0fa757\", \"gpu_unittests\": \"56aaaf101b980ae7491171bc94cac804105581b0\", \"gwp_asan_unittests\": \"9657cb74b9e311147733f000be99eceaff95edd2\", \"headless_browsertests\": \"f4e82ac3acf181ce4a503dec19875689eb6386a1\", \"headless_unittests\": \"88ae59cd8301c0ade1a81068794e2b14266c5b54\", \"install_static_unittests\": \"c8d60d15600bf4d1fc0b90d3341ca6c34b934222\", \"installer_util_unittests\": \"32f98b67c801c32c796002cd178e6f7c74da07db\", \"interactive_ui_tests\": \"1fffc44958121ae7e3b521734b57df9d547ed2b2\", \"ipc_tests\": \"1a8a436d06fc8428ed2244c7ad397ee64d933a09\", \"jingle_unittests\": \"93447fcacaa96150cbfd226684fbed1f744713fc\", \"latency_unittests\": \"2436f756be0ad7ce1afd5e58cb59b180af5cadee\", \"libjingle_xmpp_unittests\": \"d6ab69fa4ae0e9919f93315489ae1a17c5db52d7\", \"liburlpattern_unittests\": \"f350f3f185dc920ca3de1f4e1debe544b39c9d5b\", \"media_blink_unittests\": \"4b36b0b3176e09de6e4bf7b6a4470bbab6f16cd2\", \"media_unittests\": \"bfdbd4d138af8819ac47b47989a2789db978f2ca\", \"message_center_unittests\": \"c4ba1812cba4fc679012adc9f8d7c207436db26d\", \"midi_unittests\": \"d57a33a1be76b78dc70b02faa9f9c04977ca1f37\", \"mojo_core_unittests\": \"ededeaab415ffff22c4fe48f91049cdcc32b7438\", \"mojo_unittests\": \"7dae922fe1fa3b9d3a1e72b8af88afb57a72a2a7\", \"nacl_loader_unittests\": \"9fa453cb5f2b5f8fccce19659d5bd2d11c8d787e\", \"native_theme_unittests\": \"1ceb138a284bcdee0c463153da4508d1be89773b\", \"net_unittests\": \"8c540393267c93a34fe71a5c92841fe1049a2078\", \"notification_helper_unittests\": \"e436b65f47b8b011ef4ebfc59854456c98739335\", \"pdf_unittests\": \"4a9bc7ed0479d454a1dea9ac4ab4717ba7902acf\", \"perfetto_unittests\": \"dfc08f1aee0bbcf02e7a1a3a3acd0dae0d9f5240\", \"ppapi_unittests\": \"02554acc7ab1ce69fb55d88b93dbc33b119f5f22\", \"printing_unittests\": \"ff93feafb9d41ba993bc135ae6e3c8ebe3aa7c11\", \"remoting_unittests\": \"d3a1a3fa10bb74fc516f05fc9ca3490ca9330b01\", \"sbox_integration_tests\": \"7de2a5548e1ce90528bd8734e6ff6e48b747e287\", \"sbox_unittests\": \"b5c03b2cf0be033ecbe3c51e2294f53ef4516209\", \"sbox_validation_tests\": \"29ea8343b468d7b0262b59dd7bf94813f1e103d2\", \"service_manager_unittests\": \"814be5ce040ba54dee250d45cf0d5e18e7ce5a72\", \"services_unittests\": \"5382cb759b7cfc3e70b9a5fc61c04fba4b3dd0ae\", \"setup_unittests\": \"80decaddef252176e902c72f79b86c857b2b7367\", \"shell_dialogs_unittests\": \"f6a3cd2117f97dff3e7ae8a3cff24f8a684ef42b\", \"skia_unittests\": \"28ec067b0f9def092507829396f2110cc9e8e318\", \"snapshot_unittests\": \"fb0077f9a6fe2f2bfc5abf177cc1a32ccab52c2c\", \"sql_unittests\": \"7f3af85404df670c1f7d0647251892cffa99a208\", \"storage_unittests\": \"a6fc3bde02010a4774fe0abd9587a53fb3c78451\", \"sync_integration_tests\": \"10a1d1d80f5ef28f27142f7f4e92bb37470accbb\", \"traffic_annotation_auditor_unittests\": \"745e1687b5aa571ff3537d4e234d41aa68c0700e\", \"ui_base_unittests\": \"35009baf2da38e1b09a8d1f8a24bff3ef4903ab9\", \"ui_touch_selection_unittests\": \"51e186f05423b6fffd0bcfb82c5bb4a6a43b7b1a\", \"unit_tests\": \"8d1e032337e56de0a3b578cc2f16f23c9c57f4d1\", \"updater_tests\": \"6415857f6af8276e4b4c95561b9d1a7b86c8cd6f\", \"url_unittests\": \"8563c23f7e67314cd95e6f51286e86f17e2df94a\", \"views_unittests\": \"cd28ddec897c51ede142a5bfc03cdca166871605\", \"viz_unittests\": \"750a786bae277c20c6de73e2b8df2459ecd1faa7\", \"vr_common_unittests\": \"62c76ad63030a79c1c6674319e366638687d8b3e\", \"vr_pixeltests\": \"ce7f7836e4f0d4ed12e2fca6b4cee8452c767888\", \"weblayer_browsertests\": \"b66531b66d784012eda927d754769d67469ad93b\", \"weblayer_unittests\": \"f6c085990708dc677c41233b3316593eb949dd3e\", \"wm_unittests\": \"dc9e672b7dd48fc35066a437fc27e165176012ab\", \"wtf_unittests\": \"3b3f8ba0f84b39ec3a0a67d685493de970acd1ba\", \"zlib_unittests\": \"e86603549bb9dad69fd446d885cdbd1adf05b693\", \"zucchini_unittests\": \"b925579d04ae02cb22626a2b586c07ba0c5599ee\"}}, \"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-7-w73f\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610074350558173",
"status": "COMPLETED",
"status_changed_ts": "1610077956903914",
"tags": [
"build_address:luci.chromium.ci/win-asan/16685",
"builder:win-asan",
"buildset:commit/git/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0125a06da5b60b2992a124bc2bb7af8122fb0de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024138463275488752",
"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/8858708400821415392/+/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:50f8ba568e622910",
"user_agent:luci-scheduler"
],
"updated_ts": "1610077956980087",
"url": "https://ci.chromium.org/b/8858708400821415392",
"utcnow_ts": "1610077958814247"
}
}
[03:52:38.825] Invocation finished in 1h0m16.737018007s with status SUCCEEDED