[08:56:24.893] New invocation is queued and will start shortly
[08:56:26.206] Starting the invocation (attempt 1)
[08:56:26.238] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:56:26.238] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:56:26.238] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020129292965749520",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020129292965749520",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"gitiles_ref:refs/heads/master"
]
}
[08:56:27.203] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613897786361344",
"id": "8854699230726520336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613897786923639",
"tags": [
"build_address:luci.chromium.ci/win-asan/17458",
"builder:win-asan",
"buildset:commit/git/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020129292965749520",
"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/8854699230726520336/+/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": "1613897786923785",
"url": "https://ci.chromium.org/b/8854699230726520336",
"utcnow_ts": "1613897787191166"
}
}
[08:56:27.203] Task URL: https://ci.chromium.org/b/8854699230726520336
[08:56:27.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:2:0) after 1m0s
[08:56:31.193] Received PubSub notification, asking Buildbucket for the build status
[08:56:31.265] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:27.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:2:0)
[08:57:27.228] Timer tick, asking Buildbucket for the build status
[08:57:27.442] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:27.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:4:0) after 1m0s
[08:58:27.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:4:0)
[08:58:27.479] Timer tick, asking Buildbucket for the build status
[08:58:27.553] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:27.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:5:0) after 1m0s
[08:59:27.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:5:0)
[08:59:27.654] Timer tick, asking Buildbucket for the build status
[08:59:27.799] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:27.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:6:0) after 1m0s
[09:00:27.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:6:0)
[09:00:27.897] Timer tick, asking Buildbucket for the build status
[09:00:27.969] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:27.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:7:0) after 1m0s
[09:01:28.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:7:0)
[09:01:28.009] Timer tick, asking Buildbucket for the build status
[09:01:28.082] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:28.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:8:0) after 1m0s
[09:02:28.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:8:0)
[09:02:28.066] Timer tick, asking Buildbucket for the build status
[09:02:28.149] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:28.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:9:0) after 1m0s
[09:03:28.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:9:0)
[09:03:28.264] Timer tick, asking Buildbucket for the build status
[09:03:28.328] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:28.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:10:0) after 1m0s
[09:04:28.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:10:0)
[09:04:28.365] Timer tick, asking Buildbucket for the build status
[09:04:28.432] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:28.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:11:0) after 1m0s
[09:05:28.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:11:0)
[09:05:28.442] Timer tick, asking Buildbucket for the build status
[09:05:28.531] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:28.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:12:0) after 1m0s
[09:06:28.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:12:0)
[09:06:28.673] Timer tick, asking Buildbucket for the build status
[09:06:28.786] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:28.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:13:0) after 1m0s
[09:07:28.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:13:0)
[09:07:28.875] Timer tick, asking Buildbucket for the build status
[09:07:28.949] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:28.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:14:0) after 1m0s
[09:08:29.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:14:0)
[09:08:29.004] Timer tick, asking Buildbucket for the build status
[09:08:29.072] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:29.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:15:0) after 1m0s
[09:09:29.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:15:0)
[09:09:29.128] Timer tick, asking Buildbucket for the build status
[09:09:29.207] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:29.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:16:0) after 1m0s
[09:10:29.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:16:0)
[09:10:29.995] Timer tick, asking Buildbucket for the build status
[09:10:30.204] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:30.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:17:0) after 1m0s
[09:11:30.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:17:0)
[09:11:30.210] Timer tick, asking Buildbucket for the build status
[09:11:30.335] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:30.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:18:0) after 1m0s
[09:12:30.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:18:0)
[09:12:30.344] Timer tick, asking Buildbucket for the build status
[09:12:30.545] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:19:0) after 1m0s
[09:13:30.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:19:0)
[09:13:30.542] Timer tick, asking Buildbucket for the build status
[09:13:30.730] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:30.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:20:0) after 1m0s
[09:14:30.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:20:0)
[09:14:30.746] Timer tick, asking Buildbucket for the build status
[09:14:30.823] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:30.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:21:0) after 1m0s
[09:15:30.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:21:0)
[09:15:30.842] Timer tick, asking Buildbucket for the build status
[09:15:31.110] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:31.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:22:0) after 1m0s
[09:16:31.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:22:0)
[09:16:31.127] Timer tick, asking Buildbucket for the build status
[09:16:31.338] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:31.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:23:0) after 1m0s
[09:17:31.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:23:0)
[09:17:31.348] Timer tick, asking Buildbucket for the build status
[09:17:31.527] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:31.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:24:0) after 1m0s
[09:18:31.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:24:0)
[09:18:31.541] Timer tick, asking Buildbucket for the build status
[09:18:32.224] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:32.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:25:0) after 1m0s
[09:19:32.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:25:0)
[09:19:32.256] Timer tick, asking Buildbucket for the build status
[09:19:32.347] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:32.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:26:0) after 1m0s
[09:20:32.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:26:0)
[09:20:32.397] Timer tick, asking Buildbucket for the build status
[09:20:32.549] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:32.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:27:0) after 1m0s
[09:21:32.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:27:0)
[09:21:32.536] Timer tick, asking Buildbucket for the build status
[09:21:32.630] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:32.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:28:0) after 1m0s
[09:22:32.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:28:0)
[09:22:32.652] Timer tick, asking Buildbucket for the build status
[09:22:32.887] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:32.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:29:0) after 1m0s
[09:23:32.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:29:0)
[09:23:32.866] Timer tick, asking Buildbucket for the build status
[09:23:32.962] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:32.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:30:0) after 1m0s
[09:24:32.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:30:0)
[09:24:32.981] Timer tick, asking Buildbucket for the build status
[09:24:33.150] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:33.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:31:0) after 1m0s
[09:25:33.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:31:0)
[09:25:33.199] Timer tick, asking Buildbucket for the build status
[09:25:33.276] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:33.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:32:0) after 1m0s
[09:26:33.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:32:0)
[09:26:33.292] Timer tick, asking Buildbucket for the build status
[09:26:33.709] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:33.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:33:0) after 1m0s
[09:27:33.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:33:0)
[09:27:33.775] Timer tick, asking Buildbucket for the build status
[09:27:33.947] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:33.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:34:0) after 1m0s
[09:28:34.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:34:0)
[09:28:34.046] Timer tick, asking Buildbucket for the build status
[09:28:34.152] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:34.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:35:0) after 1m0s
[09:29:34.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:35:0)
[09:29:34.252] Timer tick, asking Buildbucket for the build status
[09:29:34.346] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:34.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:36:0) after 1m0s
[09:30:34.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:36:0)
[09:30:34.391] Timer tick, asking Buildbucket for the build status
[09:30:34.474] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:34.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:37:0) after 1m0s
[09:31:34.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:37:0)
[09:31:34.533] Timer tick, asking Buildbucket for the build status
[09:31:34.618] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:34.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:38:0) after 1m0s
[09:32:34.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:38:0)
[09:32:34.613] Timer tick, asking Buildbucket for the build status
[09:32:35.004] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:35.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:39:0) after 1m0s
[09:33:35.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:39:0)
[09:33:35.035] Timer tick, asking Buildbucket for the build status
[09:33:35.117] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:35.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:40:0) after 1m0s
[09:34:35.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:40:0)
[09:34:35.172] Timer tick, asking Buildbucket for the build status
[09:34:35.255] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:35.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:41:0) after 1m0s
[09:35:35.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:41:0)
[09:35:35.291] Timer tick, asking Buildbucket for the build status
[09:35:35.396] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:35.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:42:0) after 1m0s
[09:36:35.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:42:0)
[09:36:35.502] Timer tick, asking Buildbucket for the build status
[09:36:35.812] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:35.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:43:0) after 1m0s
[09:37:35.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:43:0)
[09:37:35.870] Timer tick, asking Buildbucket for the build status
[09:37:35.959] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:35.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:44:0) after 1m0s
[09:38:36.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:44:0)
[09:38:36.014] Timer tick, asking Buildbucket for the build status
[09:38:36.134] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:36.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:45:0) after 1m0s
[09:39:36.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:45:0)
[09:39:36.182] Timer tick, asking Buildbucket for the build status
[09:39:36.481] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:36.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:46:0) after 1m0s
[09:40:36.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:46:0)
[09:40:36.534] Timer tick, asking Buildbucket for the build status
[09:40:36.723] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:36.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:47:0) after 1m0s
[09:41:36.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:47:0)
[09:41:36.742] Timer tick, asking Buildbucket for the build status
[09:41:36.937] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:36.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:48:0) after 1m0s
[09:42:36.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:48:0)
[09:42:36.953] Timer tick, asking Buildbucket for the build status
[09:42:37.043] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:37.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:49:0) after 1m0s
[09:43:37.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:49:0)
[09:43:37.121] Timer tick, asking Buildbucket for the build status
[09:43:37.224] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:37.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:50:0) after 1m0s
[09:44:37.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:50:0)
[09:44:37.243] Timer tick, asking Buildbucket for the build status
[09:44:37.326] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:37.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:51:0) after 1m0s
[09:45:37.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:51:0)
[09:45:37.360] Timer tick, asking Buildbucket for the build status
[09:45:37.448] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:37.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:52:0) after 1m0s
[09:46:37.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:52:0)
[09:46:37.450] Timer tick, asking Buildbucket for the build status
[09:46:37.544] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:37.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:53:0) after 1m0s
[09:47:37.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:53:0)
[09:47:37.580] Timer tick, asking Buildbucket for the build status
[09:47:37.808] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:37.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:54:0) after 1m0s
[09:48:37.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:54:0)
[09:48:37.848] Timer tick, asking Buildbucket for the build status
[09:48:38.220] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:38.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:55:0) after 1m0s
[09:49:38.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:55:0)
[09:49:38.324] Timer tick, asking Buildbucket for the build status
[09:49:38.533] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:38.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:56:0) after 1m0s
[09:50:38.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:56:0)
[09:50:38.602] Timer tick, asking Buildbucket for the build status
[09:50:39.058] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:39.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:57:0) after 1m0s
[09:51:39.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:57:0)
[09:51:39.188] Timer tick, asking Buildbucket for the build status
[09:51:39.597] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:39.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:58:0) after 1m0s
[09:52:39.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:58:0)
[09:52:39.655] Timer tick, asking Buildbucket for the build status
[09:52:39.742] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:39.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:59:0) after 1m0s
[09:53:39.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:59:0)
[09:53:39.823] Timer tick, asking Buildbucket for the build status
[09:53:40.016] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:40.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:60:0) after 1m0s
[09:54:40.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:60:0)
[09:54:40.040] Timer tick, asking Buildbucket for the build status
[09:54:40.155] Build 8854699230726520336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:40.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020129292965749520:61:0) after 1m0s
[09:55:08.151] Received PubSub notification, asking Buildbucket for the build status
[09:55:08.249] Build 8854699230726520336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:55:08.250] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613901306289080",
"created_by": "project:chromium",
"created_ts": "1613897786361344",
"id": "8854699230726520336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\"}}",
"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\": \"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\", \"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-us-central1-b-32-1-r8z7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17458, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"6e8cdd39b19912e7fe6408b0318d0907dc7e4d21\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\", \"got_revision_cp\": \"refs/heads/master@{#856140}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf5aa15ebf3af09662d2f0e7025f724d0bd287d2\", \"swarm_hashes_refs/heads/master(at){#856140}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"04bb5d81685151cfd0d68cd4486b39f3f80b3cb0\", \"angle_unittests\": \"489219fa9b076c8082666418b4e283e0633fa0e8\", \"app_shell_unittests\": \"86ce6d559a3f64bca4d13672c2c75ad0796af1b7\", \"aura_unittests\": \"a55ea8db6228fb89ed21e8510bd6cef813655347\", \"base_unittests\": \"1ceb22df0f743b895465b4bef1cb2600b451aa3c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"aa54b0e0cb0a5945f85651dcb0efdf7d43fe9c88\", \"blink_fuzzer_unittests\": \"60573fc6aba7cb2883e443fc21eba97930b5f43b\", \"blink_heap_unittests\": \"6f5d23fa5f8522d216f6320bf6fce59bf9e29705\", \"blink_platform_unittests\": \"94dda5cfa9ebdcb15045dced0d6dfc800d798b71\", \"blink_unittests\": \"b68b31e2353aefc67ccf7286e7a6b4a3745cf492\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"7a3eca7c0eca04a4b4d13d0a9d3c69fb601908c6\", \"capture_unittests\": \"e3b9ecda46a397aef652926dd865b745eee4c328\", \"cast_unittests\": \"b84707f74c19246454be5b6f45f583b659125df8\", \"cc_unittests\": \"8b3fe8f88b9133fa3df0a37332ab592e3b78e7a9\", \"chrome_app_unittests\": \"4c92d7bd80fbe210e368f2b7e79aae9986913516\", \"chrome_cleaner_unittests\": \"55e2f47cd864115643d7551ea3c064e2464eb5fc\", \"chrome_elf_unittests\": \"25ea8d062ce0b173e79f5f3f5f8b8511042a41da\", \"chromedriver_unittests\": \"c0bfc09ae0f474aeea59d4e82917fcb918ec8d78\", \"components_browsertests\": \"edff95474787d9c24508acc86e8b3b4e1585621d\", \"components_unittests\": \"243e1d925254a0d98382ad1150059a57ac86ca71\", \"compositor_unittests\": \"7c63ffee8a47811ecfe1bdc641793061e0800cb4\", \"content_browsertests\": \"df3ab4eb5650a071208fca443038a5355bcb7801\", \"content_unittests\": \"98cbe79cf3b289d1efa7c5af8858a85700e9b369\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"9a83294f13ad35a6cf82fdebc717dfbac040d35a\", \"cronet_unittests\": \"4979a12864a1c4e1330e7ce891d4369a2e32a4ad\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"5eaeabf9ecb4608c61c184453d887f5b2bf07b13\", \"device_unittests\": \"7843a40d630ea0a50c2a3fd8ca70d7c2626edda5\", \"display_unittests\": \"0c4f5d584e2c1c5eeff2180855204802fdae2639\", \"elevation_service_unittests\": \"f3344bf19b1485453691ca936665e16a7004f23c\", \"events_unittests\": \"62ac9348f2536a55fc2688ffca32e899c89a4c56\", \"extensions_browsertests\": \"929491f7c73e54d5e66acdb9d6fd0e285319a455\", \"extensions_unittests\": \"b51c9a69721f726d4329a93957570994d2f3d0dd\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"24851e5cf10fc135255713fac97c693863cf0a73\", \"gcp_unittests\": \"bcdb0a86ee2b7075d6b8b4a6639086c5d4376bca\", \"gfx_unittests\": \"de4731e4450e4ae75f8402a6842184c59e009ca0\", \"gin_unittests\": \"4870a5d97c4ee8fa44241007482db1c691266529\", \"google_apis_unittests\": \"c7d3521637b26bdfac5393d97b0e280bd8bde35a\", \"gpu_unittests\": \"341d4fd0fd7a6bb3b0a14b265319d8668c9a7ff5\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"409b574d3a8064f600c17bf520b44ff94c30c972\", \"headless_unittests\": \"a37a4717f41afad30b8a58a11273b838697b29ce\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"6abd47cd12298726335d122aab8a53c762093a73\", \"interactive_ui_tests\": \"acc518f12447af6ef86ae789912ad6a48bafaa0d\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"2387f7f8ccaf3b5e86c265186b2747625bcbec3a\", \"latency_unittests\": \"810a5adc2e5f3a5f4d7e0e85c967d34de4c093c6\", \"libjingle_xmpp_unittests\": \"1838b44993b93f95f7ac96d3353eea76732d49b2\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"be164486843b736c09ba93c43267fd530146f0a9\", \"media_unittests\": \"aebb7889b5a2be42b9d5127658f4f1c7f44f8f59\", \"message_center_unittests\": \"22280dd80769d6753cb360e0693dffd35d8221ce\", \"midi_unittests\": \"ef799d0780a0fd3a9692026fb544836f013c613c\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"629a0dfb4452f574509e641d16313f0109f27708\", \"nacl_loader_unittests\": \"0e76ae3560435cd887a13c18ba2f7a4a630e66e8\", \"native_theme_unittests\": \"0b69c3d475061b683ff6b60101272ada5bceaba7\", \"net_unittests\": \"ee6230e4671b3460bde0ccfb2bf6bc122e624afc\", \"notification_helper_unittests\": \"ca148a1935123faaf7682a0665138b9cd5ad3aae\", \"pdf_unittests\": \"2c49ac015cdda60be1e0a9f50a6acf0207ec670f\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"9f3f95f87dd1eab5bc0eb2d053988ba9e9e71a60\", \"printing_unittests\": \"ee12f88759fe7208fb15c6cf570ced02017e71dc\", \"remoting_unittests\": \"3e862d166d216df4d809f75311773e74e2755558\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"84e59031c18384eede41ef5a79400408a363417b\", \"services_unittests\": \"174e970c8a4da4cc74d56444e60fddc87ff90487\", \"setup_unittests\": \"e5b7ce1c0661e4138cd162daeca42af49e637f03\", \"shell_dialogs_unittests\": \"0a61fd977803871b6955c5d8fe20d80ea1fc2765\", \"skia_unittests\": \"80c5fcbe9de85e38a92a4e3fcddf4efac133b38a\", \"snapshot_unittests\": \"91c7d0b19f73ca0b096ac90cffc8aec266e11fde\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"e5b59fd7341b97cefd3b9a47f8885d74294466a6\", \"sync_integration_tests\": \"99ecf4e6ed625d05d1b83e4ad51269f13e49973c\", \"traffic_annotation_auditor_unittests\": \"774059a4d1525c99a60dd3eee241c4046b561136\", \"ui_base_unittests\": \"45accba7756e2cdf7819c2d2d81d5e5f90432e12\", \"ui_touch_selection_unittests\": \"ff2fec32cac3c2c37bc00fa8887eb6f5ca19b4a4\", \"unit_tests\": \"d2944e83f6ee2f5b7422c4d88c3a142ab69eeba3\", \"updater_tests\": \"f314810bb1f8a67324e8c04eae750f7f5fd19b30\", \"url_unittests\": \"6902a7d04e4fa2df1a806861575fd250457a55e2\", \"views_unittests\": \"a0b4e263461bb31c15605c201b65f3072710e680\", \"viz_unittests\": \"1eb876b80741224be8b136720b0a18aa96c8f7cc\", \"vr_common_unittests\": \"293714247fff4afd765fbbc6229705bb6173a967\", \"vr_pixeltests\": \"46e2b040075f92591beb3d1afbf9294b2a458457\", \"weblayer_browsertests\": \"cb631d6309f5d8aed85bb203843c7b99373183d3\", \"weblayer_unittests\": \"30c91ef1586c734650873749255207b42f55fcd5\", \"wm_unittests\": \"6f429c5cc04c7b780d8b3c818a7b77a034eb378e\", \"wtf_unittests\": \"ad888a7ebf58a0eab9205cffe465acfe89e08aea\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-r8z7\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613897789804919",
"status": "COMPLETED",
"status_changed_ts": "1613901306701157",
"tags": [
"build_address:luci.chromium.ci/win-asan/17458",
"builder:win-asan",
"buildset:commit/git/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf5aa15ebf3af09662d2f0e7025f724d0bd287d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020129292965749520",
"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/8854699230726520336/+/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:51dc9f6fbe399410",
"user_agent:luci-scheduler"
],
"updated_ts": "1613901306719634",
"url": "https://ci.chromium.org/b/8854699230726520336",
"utcnow_ts": "1613901308236292"
}
}
[09:55:08.251] Invocation finished in 58m43.409243402s with status SUCCEEDED