[02:45:30.482] New invocation is queued and will start shortly
[02:45:31.655] Starting the invocation (attempt 1)
[02:45:31.696] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:45:31.696] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:45:31.696] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028034564446591040",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028034564446591040",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"gitiles_ref:refs/heads/master"
]
}
[02:45:32.897] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606358731825361",
"id": "8862604502376626784",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606358732512852",
"tags": [
"build_address:luci.chromium.ci/win-asan/15853",
"builder:win-asan",
"buildset:commit/git/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028034564446591040",
"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/8862604502376626784/+/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": "1606358732512990",
"url": "https://ci.chromium.org/b/8862604502376626784",
"utcnow_ts": "1606358732823904"
}
}
[02:45:32.897] Task URL: https://ci.chromium.org/b/8862604502376626784
[02:45:32.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:2:0) after 1m0s
[02:45:35.981] Received PubSub notification, asking Buildbucket for the build status
[02:45:36.247] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:32.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:2:0)
[02:46:32.925] Timer tick, asking Buildbucket for the build status
[02:46:33.082] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:33.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:4:0) after 1m0s
[02:47:33.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:4:0)
[02:47:33.130] Timer tick, asking Buildbucket for the build status
[02:47:33.373] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:33.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:5:0) after 1m0s
[02:48:33.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:5:0)
[02:48:33.395] Timer tick, asking Buildbucket for the build status
[02:48:33.507] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:33.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:6:0) after 1m0s
[02:49:33.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:6:0)
[02:49:33.553] Timer tick, asking Buildbucket for the build status
[02:49:33.649] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:33.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:7:0) after 1m0s
[02:50:33.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:7:0)
[02:50:33.672] Timer tick, asking Buildbucket for the build status
[02:50:33.741] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:33.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:8:0) after 1m0s
[02:51:33.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:8:0)
[02:51:33.738] Timer tick, asking Buildbucket for the build status
[02:51:34.037] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:9:0) after 1m0s
[02:52:34.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:9:0)
[02:52:34.086] Timer tick, asking Buildbucket for the build status
[02:52:34.247] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:34.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:10:0) after 1m0s
[02:53:34.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:10:0)
[02:53:34.264] Timer tick, asking Buildbucket for the build status
[02:53:34.374] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:34.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:11:0) after 1m0s
[02:54:34.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:11:0)
[02:54:34.456] Timer tick, asking Buildbucket for the build status
[02:54:34.511] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:34.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:12:0) after 1m0s
[02:55:34.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:12:0)
[02:55:34.566] Timer tick, asking Buildbucket for the build status
[02:55:34.769] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:34.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:13:0) after 1m0s
[02:56:34.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:13:0)
[02:56:34.816] Timer tick, asking Buildbucket for the build status
[02:56:34.968] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:34.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:14:0) after 1m0s
[02:57:35.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:14:0)
[02:57:35.007] Timer tick, asking Buildbucket for the build status
[02:57:35.257] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:35.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:15:0) after 1m0s
[02:58:35.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:15:0)
[02:58:35.313] Timer tick, asking Buildbucket for the build status
[02:58:35.400] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:35.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:16:0) after 1m0s
[02:59:35.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:16:0)
[02:59:35.554] Timer tick, asking Buildbucket for the build status
[02:59:36.105] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:36.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:17:0) after 1m0s
[03:00:36.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:17:0)
[03:00:36.097] Timer tick, asking Buildbucket for the build status
[03:00:36.194] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:18:0) after 1m0s
[03:01:36.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:18:0)
[03:01:36.219] Timer tick, asking Buildbucket for the build status
[03:01:36.284] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:36.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:19:0) after 1m0s
[03:02:36.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:19:0)
[03:02:36.377] Timer tick, asking Buildbucket for the build status
[03:02:36.469] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:36.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:20:0) after 1m0s
[03:03:36.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:20:0)
[03:03:36.526] Timer tick, asking Buildbucket for the build status
[03:03:36.655] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:36.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:21:0) after 1m0s
[03:04:36.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:21:0)
[03:04:36.901] Timer tick, asking Buildbucket for the build status
[03:04:37.039] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:22:0) after 1m0s
[03:05:37.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:22:0)
[03:05:37.065] Timer tick, asking Buildbucket for the build status
[03:05:37.356] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:37.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:23:0) after 1m0s
[03:06:37.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:23:0)
[03:06:37.418] Timer tick, asking Buildbucket for the build status
[03:06:37.579] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:37.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:24:0) after 1m0s
[03:07:37.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:24:0)
[03:07:37.579] Timer tick, asking Buildbucket for the build status
[03:07:37.679] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:37.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:25:0) after 1m0s
[03:08:37.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:25:0)
[03:08:37.728] Timer tick, asking Buildbucket for the build status
[03:08:37.802] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:37.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:26:0) after 1m0s
[03:09:37.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:26:0)
[03:09:37.820] Timer tick, asking Buildbucket for the build status
[03:09:38.012] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:27:0) after 1m0s
[03:10:38.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:27:0)
[03:10:38.019] Timer tick, asking Buildbucket for the build status
[03:10:38.122] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:38.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:28:0) after 1m0s
[03:11:38.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:28:0)
[03:11:38.282] Timer tick, asking Buildbucket for the build status
[03:11:38.418] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:38.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:29:0) after 1m0s
[03:12:38.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:29:0)
[03:12:38.536] Timer tick, asking Buildbucket for the build status
[03:12:39.108] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:39.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:30:0) after 1m0s
[03:13:39.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:30:0)
[03:13:39.135] Timer tick, asking Buildbucket for the build status
[03:13:39.235] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:39.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:31:0) after 1m0s
[03:14:39.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:31:0)
[03:14:39.271] Timer tick, asking Buildbucket for the build status
[03:14:40.088] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:40.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:32:0) after 1m0s
[03:15:40.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:32:0)
[03:15:40.113] Timer tick, asking Buildbucket for the build status
[03:15:40.220] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:40.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:33:0) after 1m0s
[03:16:40.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:33:0)
[03:16:40.259] Timer tick, asking Buildbucket for the build status
[03:16:40.707] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:40.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:34:0) after 1m0s
[03:17:40.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:34:0)
[03:17:40.742] Timer tick, asking Buildbucket for the build status
[03:17:41.352] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:41.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:35:0) after 1m0s
[03:18:41.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:35:0)
[03:18:41.373] Timer tick, asking Buildbucket for the build status
[03:18:41.469] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:41.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:36:0) after 1m0s
[03:19:41.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:36:0)
[03:19:41.466] Timer tick, asking Buildbucket for the build status
[03:19:41.582] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:41.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:37:0) after 1m0s
[03:20:41.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:37:0)
[03:20:41.636] Timer tick, asking Buildbucket for the build status
[03:20:41.969] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:41.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:38:0) after 1m0s
[03:21:41.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:38:0)
[03:21:41.992] Timer tick, asking Buildbucket for the build status
[03:21:42.088] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:42.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:39:0) after 1m0s
[03:22:42.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:39:0)
[03:22:42.072] Timer tick, asking Buildbucket for the build status
[03:22:42.246] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:40:0) after 1m0s
[03:23:42.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:40:0)
[03:23:42.308] Timer tick, asking Buildbucket for the build status
[03:23:42.752] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:42.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:41:0) after 1m0s
[03:24:42.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:41:0)
[03:24:42.808] Timer tick, asking Buildbucket for the build status
[03:24:42.959] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:42:0) after 1m0s
[03:25:43.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:42:0)
[03:25:43.052] Timer tick, asking Buildbucket for the build status
[03:25:43.186] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:43.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:43:0) after 1m0s
[03:26:43.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:43:0)
[03:26:43.208] Timer tick, asking Buildbucket for the build status
[03:26:43.331] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:43.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:44:0) after 1m0s
[03:27:43.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:44:0)
[03:27:43.361] Timer tick, asking Buildbucket for the build status
[03:27:43.469] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:43.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:45:0) after 1m0s
[03:28:43.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:45:0)
[03:28:43.521] Timer tick, asking Buildbucket for the build status
[03:28:43.588] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:43.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:46:0) after 1m0s
[03:29:43.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:46:0)
[03:29:43.575] Timer tick, asking Buildbucket for the build status
[03:29:43.662] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:43.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:47:0) after 1m0s
[03:30:43.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:47:0)
[03:30:43.683] Timer tick, asking Buildbucket for the build status
[03:30:43.794] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:48:0) after 1m0s
[03:31:43.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:48:0)
[03:31:43.843] Timer tick, asking Buildbucket for the build status
[03:31:43.954] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:49:0) after 1m0s
[03:32:43.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:49:0)
[03:32:43.978] Timer tick, asking Buildbucket for the build status
[03:32:44.471] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:44.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:50:0) after 1m0s
[03:33:44.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:50:0)
[03:33:44.508] Timer tick, asking Buildbucket for the build status
[03:33:44.635] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:44.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:51:0) after 1m0s
[03:34:44.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:51:0)
[03:34:44.656] Timer tick, asking Buildbucket for the build status
[03:34:44.777] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:44.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:52:0) after 1m0s
[03:35:44.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:52:0)
[03:35:44.798] Timer tick, asking Buildbucket for the build status
[03:35:44.910] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:44.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:53:0) after 1m0s
[03:36:44.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:53:0)
[03:36:44.985] Timer tick, asking Buildbucket for the build status
[03:36:45.059] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:45.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:54:0) after 1m0s
[03:37:45.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:54:0)
[03:37:45.152] Timer tick, asking Buildbucket for the build status
[03:37:45.225] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:45.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:55:0) after 1m0s
[03:38:45.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:55:0)
[03:38:45.383] Timer tick, asking Buildbucket for the build status
[03:38:45.503] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:45.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:56:0) after 1m0s
[03:39:45.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:56:0)
[03:39:45.540] Timer tick, asking Buildbucket for the build status
[03:39:45.801] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:45.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:57:0) after 1m0s
[03:40:45.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:57:0)
[03:40:45.885] Timer tick, asking Buildbucket for the build status
[03:40:46.074] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:46.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:58:0) after 1m0s
[03:41:46.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:58:0)
[03:41:46.151] Timer tick, asking Buildbucket for the build status
[03:41:46.433] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:46.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:59:0) after 1m0s
[03:42:46.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:59:0)
[03:42:46.598] Timer tick, asking Buildbucket for the build status
[03:42:46.819] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:46.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:60:0) after 1m0s
[03:43:46.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:60:0)
[03:43:46.923] Timer tick, asking Buildbucket for the build status
[03:43:47.031] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:47.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:61:0) after 1m0s
[03:44:47.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:61:0)
[03:44:47.112] Timer tick, asking Buildbucket for the build status
[03:44:47.232] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:47.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:62:0) after 1m0s
[03:45:47.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:62:0)
[03:45:47.256] Timer tick, asking Buildbucket for the build status
[03:45:47.357] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:47.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:63:0) after 1m0s
[03:46:47.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:63:0)
[03:46:47.449] Timer tick, asking Buildbucket for the build status
[03:46:47.522] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:47.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:64:0) after 1m0s
[03:47:47.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:64:0)
[03:47:47.577] Timer tick, asking Buildbucket for the build status
[03:47:47.655] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:47.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:65:0) after 1m0s
[03:48:47.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:65:0)
[03:48:47.685] Timer tick, asking Buildbucket for the build status
[03:48:47.814] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:47.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:66:0) after 1m0s
[03:49:47.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:66:0)
[03:49:47.798] Timer tick, asking Buildbucket for the build status
[03:49:47.865] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:47.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:67:0) after 1m0s
[03:50:47.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:67:0)
[03:50:47.886] Timer tick, asking Buildbucket for the build status
[03:50:48.021] Build 8862604502376626784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:48.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028034564446591040:68:0) after 1m0s
[03:51:30.176] Received PubSub notification, asking Buildbucket for the build status
[03:51:30.241] Build 8862604502376626784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:51:30.241] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606362689338714",
"created_by": "project:chromium",
"created_ts": "1606358731825361",
"id": "8862604502376626784",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\"}}",
"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\": \"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\", \"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-6-2gr3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15853, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"4dba276776bda27af2e6422f699b0c3429399e24\", \"got_dawn_revision\": \"9175f00eec91c6c9b3c3e264d6d8ec7879881844\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\", \"got_revision_cp\": \"refs/heads/master@{#831226}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"fe46597df7664f8fe317c2363cfad28007283e75\", \"got_v8_revision_cp\": \"refs/heads/8.9.53@{#1}\", \"got_webrtc_revision\": \"b6b599fc588e59142df343d2d2897203468aacea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32695}\", \"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\": \"6f6923160e81a4c535a6d8e09db0f792ee9dc0bd\", \"swarm_hashes_refs/heads/master(at){#831226}_without_patch\": {\"absl_hardening_tests\": \"589052909a0f563361afc8e0ff2f47f74342ba32\", \"accessibility_unittests\": \"4ed82f350c4288952342177d521bcaa9aff5349a\", \"angle_unittests\": \"07e0636b16f2cab2de3ec63f36c0ba5bfbe48cc0\", \"app_shell_unittests\": \"2b3f513d5c614fd7decdf610753c7575010bb7e7\", \"aura_unittests\": \"10ab317cb1dfadb5fc6c0368718d2a21ef371605\", \"base_unittests\": \"4b87718f10ef3de6927b85d8b79e8352940a27b4\", \"base_util_unittests\": \"eaf3416a68c1151e32f608bcb926fe5f02f81d23\", \"blink_common_unittests\": \"b0d0b7534b25a6f27a6d481b8770ed5bb9aa2499\", \"blink_fuzzer_unittests\": \"6186cdbcfdb4d2e53b86795feb08f8a2c94f79c1\", \"blink_heap_unittests\": \"d131f8bce4cd99c8028ac961ba90f84ccd72b8db\", \"blink_platform_unittests\": \"bfd316e6464b19e50c90d1b5f24dab1963abef6d\", \"blink_unittests\": \"b742f342cf35c285daca17cfcaa706f98a7f4940\", \"boringssl_crypto_tests\": \"998a7e50fda05d71a52494aec9f5e6b1a9eab583\", \"boringssl_ssl_tests\": \"10af31cda7e917b80227f5e61c007c8ce2c1efb5\", \"browser_switcher_bho_unittests\": \"f0ddcbe046980f97ec162ddab61f643e0b65dfab\", \"browser_tests\": \"9ea63aa2249b06a7767e8e521fb7acdbd2bbc996\", \"capture_unittests\": \"9672187e5e9a215fe191d9ba615f4e29f3f0d79c\", \"cast_unittests\": \"6fe8dc5165865bac9b385a333fde1f9f1d54fb45\", \"cc_unittests\": \"a8d96e5b01e3bdab36c4026267c85343a87a15ad\", \"chrome_app_unittests\": \"031d6e23b142be5f6271b36b5dbca24b310b1ad1\", \"chrome_cleaner_unittests\": \"766e8e0326f4f14fd7237f0f07c9413a058b9655\", \"chrome_elf_unittests\": \"912b8abdcef70591c9b687c6298fe21fbe87258f\", \"chromedriver_unittests\": \"2560b6be18c3d8fc117e6b6711a36da78fa6330e\", \"components_browsertests\": \"a89d025b92e669e52d01c4df202cd46fe1dcfd16\", \"components_unittests\": \"2560b4c92e7ae814aecbb120d8aaa33de0155750\", \"compositor_unittests\": \"c654622efc93b443d8d370691ca2a77a42ac460b\", \"content_browsertests\": \"544723050dd8c3df546ad39dd58e1986402f4c16\", \"content_unittests\": \"5048612fc718a33a59a81953d5a52b7d3ea592fe\", \"courgette_unittests\": \"60fe0b7b040a2370744eb0c3dbb152f0e100687c\", \"crashpad_tests\": \"5fa4520bb8793bdf4add959b82f4cc952fd7d20c\", \"cronet_tests\": \"100e4eea6894f09f54fc827b5bc5b1ac92c7ca96\", \"cronet_unittests\": \"5abe14f551d4096d90f905c08e56ff80d38f7a3f\", \"crypto_unittests\": \"f0968e4d53b33615d259907681cc7a045746aacf\", \"delayloads_unittests\": \"82e4d44946721f5928b9df6b90b081acac298570\", \"device_unittests\": \"a3d5e3fccd2d41ffd57bad94abf55a4c6785622a\", \"display_unittests\": \"c0884fb636e6f3bd245709714b560e33cbf79a01\", \"elevation_service_unittests\": \"67e40a3574e60cb1071d9514f29d26789ea54884\", \"events_unittests\": \"b6a014a87d68b5019499111160a06c90ee6902b0\", \"extensions_browsertests\": \"c2da51cb901517d51bfc778893c2c463da668f6c\", \"extensions_unittests\": \"e5e68dba1e6159856aa6f673a1bc3ae4a1a54181\", \"filesystem_service_unittests\": \"1765d2696996ab104f4f919e75c963242f375c81\", \"gcm_unit_tests\": \"ac0c2b9aa6ae05d2422793ba2006fcd60410ecbe\", \"gcp_unittests\": \"d4700e120eb784b768780dfe0628ff38fa55bef7\", \"gfx_unittests\": \"8b7e74ddfe89b15cc8a9d22c139076386a8dd753\", \"gin_unittests\": \"3112927d455af94584acfae5f790921c120a6f3f\", \"google_apis_unittests\": \"841d5cddd7ee191713a37ae364d774e15a1b7ef0\", \"gpu_unittests\": \"62fe2eac11311ff1f274d57d4788a2caecf477f8\", \"gwp_asan_unittests\": \"0ffe2747aed959e2c1e8d0f08c44b18e99a08552\", \"headless_browsertests\": \"600cc85335e74f97b82008a68a350d9b55332247\", \"headless_unittests\": \"cf96ef85ed6140885dcd8d1fa7ff252504a1ca4d\", \"install_static_unittests\": \"9f94fc96725001c20a8c7f718fc51bb403e53421\", \"installer_util_unittests\": \"6d66f163574c7aba83e1130bd39f34cfc016c9f5\", \"interactive_ui_tests\": \"6276ed45b0240a7394b6a045e6ec22993fb0fd72\", \"ipc_tests\": \"95c8071d31e530f1f76b2e7fa0d353691a2ea521\", \"jingle_unittests\": \"418101359867bc38b8bae9e9563437fc844aebe0\", \"latency_unittests\": \"8c9f9214e4d5fc26ae474b2ef90ff3950d3aeaec\", \"libjingle_xmpp_unittests\": \"56d61e1637484e23b941abde938db7321fc11142\", \"liburlpattern_unittests\": \"1abb7b2f35fcb5c4991521692d67cde7df284483\", \"media_blink_unittests\": \"929e1f8b3d17c6bc860aaafd2284c1167e9419d4\", \"media_unittests\": \"3e2ee77c4318174082f66388fc28859d3c927682\", \"message_center_unittests\": \"bacc4d3ab0c9fbc9b526eb81581f9c3a0914632b\", \"midi_unittests\": \"fded0c123e29f10c75c7bced44bed6d302cd8c57\", \"mojo_core_unittests\": \"b88589239b698e1f347a7a92a6b9050d6b0ccfa8\", \"mojo_unittests\": \"f2e062b3d7be658d5ac86143504a080d783f3069\", \"nacl_loader_unittests\": \"e727d67b6d8ff5b491a499ce0fa103b19c5816b4\", \"native_theme_unittests\": \"76b2b278ffd495673817c2f4841b37770c3c18f2\", \"net_unittests\": \"01df33e74bb014fae0d8241868fd7532377059b0\", \"notification_helper_unittests\": \"2ae2341f45078687a9c89886e2bb58533e6b9fb5\", \"pdf_unittests\": \"ca9d6fee22649e10cea10b1f6507e8dd3b04432c\", \"perfetto_unittests\": \"46ff3dce1c04a60c534b515f9525a22db253478b\", \"ppapi_unittests\": \"d98f2d6b1e132db23156f2d620ad144bd32e07c6\", \"printing_unittests\": \"f1c751bd1e70136361c9e4b4e462ec3eda554856\", \"remoting_unittests\": \"708069c0fb006d6582684ca5254172cabc3e04f3\", \"sbox_integration_tests\": \"ba20109961abc970e89f9ee091b58b8b302c05b1\", \"sbox_unittests\": \"1e4a3cb6dfbdca40fdd987a5e2dcfb9ea200f8f1\", \"sbox_validation_tests\": \"ae2e9e0240c17da2b4e8718c6fa8b07841303c40\", \"service_manager_unittests\": \"8d5a26abeec89f6ebe14b64fc9f636d1a2dbf250\", \"services_unittests\": \"6433edb86146c350eea3e389eb706cc8512814c3\", \"setup_unittests\": \"45e39d941050b3136398f4c6338f7fb1c433828e\", \"shell_dialogs_unittests\": \"c4eb9c62f802abd8026471fec2d463a8c6deaa47\", \"skia_unittests\": \"3c4316dcd139d87da5668dedb78a60c960a15163\", \"snapshot_unittests\": \"b8af58331fca59d22f9c097c1b911e60168d7df7\", \"sql_unittests\": \"6c375ecb413d32bd14c9d4eebce028d10fcf1c86\", \"storage_unittests\": \"36e9850d05bb40af39c2e590e9bf72ef73a920e8\", \"sync_integration_tests\": \"c2205e6c55d64c0c4d25f5e9d371c105216d7e45\", \"traffic_annotation_auditor_unittests\": \"facd0f4275ff5a98ae484033244f8c90b2e0af88\", \"ui_base_unittests\": \"4b86b1062ba9b17d85ea3ce8b18a5f40ebeb371f\", \"ui_touch_selection_unittests\": \"14c487d1d4e3d42f570db3af23283c1fb04e76a8\", \"unit_tests\": \"ac2a297aac336ff45da3fdd820d90b704693b411\", \"updater_tests\": \"36a40523748eab222b6872e19bddabbf43121ef5\", \"url_unittests\": \"48cb39623487a44affc11bbe2f5155732c8f8d69\", \"views_unittests\": \"1b57ee1253669bf0ff28bbf154c2930dfa91bc22\", \"viz_unittests\": \"c66f2077cf5b385f8fa6e061774c4f5a3ba2f5e4\", \"vr_common_unittests\": \"51c910bcecd20bd64231680c95b452aa216744d4\", \"vr_pixeltests\": \"01a64a31dbf644d58f27787381cbf76d723ad534\", \"weblayer_browsertests\": \"a7c4b292ce08c7c35ba91ffeec5bc11e6d396cfa\", \"weblayer_unittests\": \"a289f97660fe196db42903acc7d48d768fe316d7\", \"wm_unittests\": \"81b326a3a0380c72f5763ef852d65a838a0bf420\", \"wtf_unittests\": \"030187d328dd9c85bdf2b937af44d2bf60a18c00\", \"zlib_unittests\": \"5cc1250709187e82b3231fab7c8e28c29d5e3766\", \"zucchini_unittests\": \"da0d63d422dd45f775e2be85f0e3e3e45c66c35b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-2gr3\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606358735037786",
"status": "COMPLETED",
"status_changed_ts": "1606362689664229",
"tags": [
"build_address:luci.chromium.ci/win-asan/15853",
"builder:win-asan",
"buildset:commit/git/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6f6923160e81a4c535a6d8e09db0f792ee9dc0bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028034564446591040",
"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/8862604502376626784/+/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:501b42986bbfc710",
"user_agent:luci-scheduler"
],
"updated_ts": "1606362689668170",
"url": "https://ci.chromium.org/b/8862604502376626784",
"utcnow_ts": "1606362690233012"
}
}
[03:51:30.242] Invocation finished in 1h5m59.774715067s with status SUCCEEDED