[12:09:20.638] New invocation is queued and will start shortly
[12:09:21.785] Starting the invocation (attempt 1)
[12:09:21.835] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:09:21.835] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:09:21.835] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028723866691282416",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c780b01e35977685e78c948c6badc46ccd0e6333\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028723866691282416",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c780b01e35977685e78c948c6badc46ccd0e6333",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c780b01e35977685e78c948c6badc46ccd0e6333",
"gitiles_ref:refs/heads/master"
]
}
[12:09:22.824] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605701362247654",
"id": "8863293804338400352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c780b01e35977685e78c948c6badc46ccd0e6333\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605701362691856",
"tags": [
"build_address:luci.chromium.ci/win-asan/15704",
"builder:win-asan",
"buildset:commit/git/c780b01e35977685e78c948c6badc46ccd0e6333",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c780b01e35977685e78c948c6badc46ccd0e6333",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028723866691282416",
"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/8863293804338400352/+/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": "1605701362692013",
"url": "https://ci.chromium.org/b/8863293804338400352",
"utcnow_ts": "1605701362811715"
}
}
[12:09:22.824] Task URL: https://ci.chromium.org/b/8863293804338400352
[12:09:22.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:2:0) after 1m0s
[12:09:24.867] Received PubSub notification, asking Buildbucket for the build status
[12:09:25.660] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:22.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:2:0)
[12:10:22.967] Timer tick, asking Buildbucket for the build status
[12:10:23.067] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:23.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:4:0) after 1m0s
[12:11:23.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:4:0)
[12:11:23.146] Timer tick, asking Buildbucket for the build status
[12:11:23.245] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:23.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:5:0) after 1m0s
[12:12:23.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:5:0)
[12:12:23.319] Timer tick, asking Buildbucket for the build status
[12:12:23.427] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:23.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:6:0) after 1m0s
[12:13:23.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:6:0)
[12:13:23.520] Timer tick, asking Buildbucket for the build status
[12:13:23.850] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:23.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:7:0) after 1m0s
[12:14:23.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:7:0)
[12:14:23.929] Timer tick, asking Buildbucket for the build status
[12:14:24.441] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:24.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:8:0) after 1m0s
[12:15:24.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:8:0)
[12:15:24.542] Timer tick, asking Buildbucket for the build status
[12:15:24.628] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:24.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:9:0) after 1m0s
[12:16:24.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:9:0)
[12:16:24.708] Timer tick, asking Buildbucket for the build status
[12:16:24.798] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:24.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:10:0) after 1m0s
[12:17:24.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:10:0)
[12:17:24.885] Timer tick, asking Buildbucket for the build status
[12:17:25.053] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:25.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:11:0) after 1m0s
[12:18:25.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:11:0)
[12:18:25.116] Timer tick, asking Buildbucket for the build status
[12:18:25.184] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:25.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:12:0) after 1m0s
[12:19:25.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:12:0)
[12:19:25.250] Timer tick, asking Buildbucket for the build status
[12:19:25.371] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:25.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:13:0) after 1m0s
[12:20:25.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:13:0)
[12:20:25.434] Timer tick, asking Buildbucket for the build status
[12:20:25.499] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:25.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:14:0) after 1m0s
[12:21:25.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:14:0)
[12:21:25.609] Timer tick, asking Buildbucket for the build status
[12:21:25.713] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:25.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:15:0) after 1m0s
[12:22:25.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:15:0)
[12:22:25.818] Timer tick, asking Buildbucket for the build status
[12:22:25.924] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:25.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:16:0) after 1m0s
[12:23:26.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:16:0)
[12:23:26.026] Timer tick, asking Buildbucket for the build status
[12:23:26.511] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:26.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:17:0) after 1m0s
[12:24:26.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:17:0)
[12:24:26.533] Timer tick, asking Buildbucket for the build status
[12:24:26.694] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:26.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:18:0) after 1m0s
[12:25:26.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:18:0)
[12:25:26.822] Timer tick, asking Buildbucket for the build status
[12:25:26.940] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:26.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:19:0) after 1m0s
[12:26:26.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:19:0)
[12:26:26.946] Timer tick, asking Buildbucket for the build status
[12:26:27.156] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:27.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:20:0) after 1m0s
[12:27:27.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:20:0)
[12:27:27.185] Timer tick, asking Buildbucket for the build status
[12:27:27.327] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:27.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:21:0) after 1m0s
[12:28:27.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:21:0)
[12:28:27.354] Timer tick, asking Buildbucket for the build status
[12:28:27.538] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:27.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:22:0) after 1m0s
[12:29:27.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:22:0)
[12:29:27.569] Timer tick, asking Buildbucket for the build status
[12:29:27.693] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:27.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:23:0) after 1m0s
[12:30:27.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:23:0)
[12:30:27.782] Timer tick, asking Buildbucket for the build status
[12:30:27.986] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:27.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:24:0) after 1m0s
[12:31:28.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:24:0)
[12:31:28.028] Timer tick, asking Buildbucket for the build status
[12:31:30.404] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:30.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:25:0) after 1m0s
[12:32:30.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:25:0)
[12:32:30.495] Timer tick, asking Buildbucket for the build status
[12:32:30.625] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:30.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:26:0) after 1m0s
[12:33:30.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:26:0)
[12:33:30.754] Timer tick, asking Buildbucket for the build status
[12:33:30.869] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:30.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:27:0) after 1m0s
[12:34:30.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:27:0)
[12:34:30.978] Timer tick, asking Buildbucket for the build status
[12:34:31.086] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:31.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:28:0) after 1m0s
[12:35:31.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:28:0)
[12:35:31.348] Timer tick, asking Buildbucket for the build status
[12:35:32.038] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:32.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:29:0) after 1m0s
[12:36:32.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:29:0)
[12:36:32.104] Timer tick, asking Buildbucket for the build status
[12:36:32.174] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:32.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:30:0) after 1m0s
[12:37:32.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:30:0)
[12:37:32.199] Timer tick, asking Buildbucket for the build status
[12:37:32.507] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:32.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:31:0) after 1m0s
[12:38:32.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:31:0)
[12:38:32.669] Timer tick, asking Buildbucket for the build status
[12:38:32.756] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:32.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:32:0) after 1m0s
[12:39:32.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:32:0)
[12:39:32.869] Timer tick, asking Buildbucket for the build status
[12:39:33.019] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:33.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:33:0) after 1m0s
[12:40:33.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:33:0)
[12:40:33.037] Timer tick, asking Buildbucket for the build status
[12:40:33.209] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:33.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:34:0) after 1m0s
[12:41:33.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:34:0)
[12:41:33.265] Timer tick, asking Buildbucket for the build status
[12:41:33.376] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:35:0) after 1m0s
[12:42:33.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:35:0)
[12:42:33.519] Timer tick, asking Buildbucket for the build status
[12:42:33.683] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:33.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:36:0) after 1m0s
[12:43:33.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:36:0)
[12:43:33.711] Timer tick, asking Buildbucket for the build status
[12:43:33.803] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:33.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:37:0) after 1m0s
[12:44:33.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:37:0)
[12:44:33.827] Timer tick, asking Buildbucket for the build status
[12:44:33.957] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:38:0) after 1m0s
[12:45:33.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:38:0)
[12:45:33.937] Timer tick, asking Buildbucket for the build status
[12:45:34.038] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:34.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:39:0) after 1m0s
[12:46:34.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:39:0)
[12:46:34.130] Timer tick, asking Buildbucket for the build status
[12:46:34.208] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:34.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:40:0) after 1m0s
[12:47:34.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:40:0)
[12:47:34.334] Timer tick, asking Buildbucket for the build status
[12:47:34.521] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:34.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:41:0) after 1m0s
[12:48:34.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:41:0)
[12:48:34.734] Timer tick, asking Buildbucket for the build status
[12:48:34.869] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:42:0) after 1m0s
[12:49:34.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:42:0)
[12:49:34.935] Timer tick, asking Buildbucket for the build status
[12:49:35.058] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:35.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:43:0) after 1m0s
[12:50:35.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:43:0)
[12:50:35.297] Timer tick, asking Buildbucket for the build status
[12:50:35.423] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:35.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:44:0) after 1m0s
[12:51:35.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:44:0)
[12:51:35.434] Timer tick, asking Buildbucket for the build status
[12:51:35.614] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:35.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:45:0) after 1m0s
[12:52:35.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:45:0)
[12:52:35.699] Timer tick, asking Buildbucket for the build status
[12:52:35.804] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:35.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:46:0) after 1m0s
[12:53:35.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:46:0)
[12:53:35.923] Timer tick, asking Buildbucket for the build status
[12:53:36.011] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:36.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:47:0) after 1m0s
[12:54:36.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:47:0)
[12:54:36.076] Timer tick, asking Buildbucket for the build status
[12:54:36.608] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:36.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:48:0) after 1m0s
[12:55:36.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:48:0)
[12:55:36.686] Timer tick, asking Buildbucket for the build status
[12:55:37.205] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:37.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:49:0) after 1m0s
[12:56:37.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:49:0)
[12:56:37.264] Timer tick, asking Buildbucket for the build status
[12:56:37.429] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:37.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:50:0) after 1m0s
[12:57:37.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:50:0)
[12:57:37.483] Timer tick, asking Buildbucket for the build status
[12:57:37.564] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:37.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:51:0) after 1m0s
[12:58:37.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:51:0)
[12:58:37.633] Timer tick, asking Buildbucket for the build status
[12:58:37.895] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:37.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:52:0) after 1m0s
[12:59:37.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:52:0)
[12:59:37.975] Timer tick, asking Buildbucket for the build status
[12:59:38.224] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:38.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:53:0) after 1m0s
[13:00:38.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:53:0)
[13:00:38.269] Timer tick, asking Buildbucket for the build status
[13:00:38.516] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:38.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:54:0) after 1m0s
[13:01:38.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:54:0)
[13:01:38.536] Timer tick, asking Buildbucket for the build status
[13:01:38.644] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:38.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:55:0) after 1m0s
[13:02:38.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:55:0)
[13:02:38.636] Timer tick, asking Buildbucket for the build status
[13:02:38.700] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:38.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:56:0) after 1m0s
[13:03:38.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:56:0)
[13:03:38.775] Timer tick, asking Buildbucket for the build status
[13:03:38.928] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:38.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:57:0) after 1m0s
[13:04:38.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:57:0)
[13:04:38.960] Timer tick, asking Buildbucket for the build status
[13:04:39.321] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:58:0) after 1m0s
[13:05:39.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:58:0)
[13:05:39.353] Timer tick, asking Buildbucket for the build status
[13:05:39.709] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:39.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:59:0) after 1m0s
[13:06:39.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:59:0)
[13:06:39.736] Timer tick, asking Buildbucket for the build status
[13:06:39.812] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:39.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:60:0) after 1m0s
[13:07:39.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:60:0)
[13:07:39.841] Timer tick, asking Buildbucket for the build status
[13:07:39.967] Build 8863293804338400352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:39.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028723866691282416:61:0) after 1m0s
[13:08:25.899] Received PubSub notification, asking Buildbucket for the build status
[13:08:25.966] Build 8863293804338400352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:08:25.967] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605704904580127",
"created_by": "project:chromium",
"created_ts": "1605701362247654",
"id": "8863293804338400352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c780b01e35977685e78c948c6badc46ccd0e6333\"}}",
"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\": \"c780b01e35977685e78c948c6badc46ccd0e6333\", \"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-8-6zek\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15704, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"2ffff6d024c7d639ecf4258402c2d047bb09588a\", \"got_dawn_revision\": \"55f251dffe05a471f5979a315fa6fee54804e3b8\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"c780b01e35977685e78c948c6badc46ccd0e6333\", \"got_revision_cp\": \"refs/heads/master@{#828673}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"bb96f79d0e15daa9af7b11b2868baeb052d136d9\", \"got_v8_revision\": \"3644d1fc3b7f59e11f5e80b6feb79634736e0192\", \"got_v8_revision_cp\": \"refs/heads/8.9.7@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"c780b01e35977685e78c948c6badc46ccd0e6333\", \"swarm_hashes_refs/heads/master(at){#828673}_without_patch\": {\"absl_hardening_tests\": \"0679ea15f949ffcc7ee0a58a17b819019893db87\", \"accessibility_unittests\": \"0c6d11a10475243400bafdb0024209bf5aeb66f9\", \"angle_unittests\": \"313f7a1eec76b915a5b487c47573f6326e7885e5\", \"app_shell_unittests\": \"c54943677ce0122365d17a9cc6296951a7b13c8a\", \"aura_unittests\": \"92fad48c930d9528a629fe05ca562fe00af8c9a9\", \"base_unittests\": \"3c63cc3952a40a9937cf24feee593c932a49d444\", \"base_util_unittests\": \"97974c8642e4c8d9c09e602bfb810832a480c288\", \"blink_common_unittests\": \"5662460384d7fd8dc7a168b32e777c02291f4920\", \"blink_fuzzer_unittests\": \"be293b175a4e0508b9c55e43e7a454dbbf783244\", \"blink_heap_unittests\": \"f688aa2873e7b18d5010f33ed85c62b79d0f1b6c\", \"blink_platform_unittests\": \"cd5e5d5be17fbbfe19ab2b08a46c574215ddffe3\", \"blink_unittests\": \"779aa050e3d57e29162b1228b35e82961f3bd0ef\", \"boringssl_crypto_tests\": \"2153e80236515e586923df1672e4662c1993362d\", \"boringssl_ssl_tests\": \"eb4ada3deb97bcd98cd1971a5a9937e1f618f5fb\", \"browser_switcher_bho_unittests\": \"e1404b439c6bfb0c4ba4b6ba15da5fab9728ae09\", \"browser_tests\": \"5904e8d74f451bc00014a9ab0a23d361bd94acf4\", \"capture_unittests\": \"48e39f10b036827917a9a0542c40370951329530\", \"cast_unittests\": \"dc6814bd2b8aa0222172ae3af6fc6889e1422567\", \"cc_unittests\": \"919d39c9bb0f933326b4824a1c636b4ccf868e59\", \"chrome_app_unittests\": \"d99b9b3828ce79b201ce618327e5c95bc8aff2bc\", \"chrome_cleaner_unittests\": \"86f3b775825e6ae16928fd7fe0fc79749d5f18ee\", \"chrome_elf_unittests\": \"9c4e542746264b258711c24ac96878614027457b\", \"chromedriver_unittests\": \"b55e8a72b52ede972f721529b71defe4dacc70bd\", \"components_browsertests\": \"98f33ee1e9bfaa14c4aba06b4b85a6552df8146a\", \"components_unittests\": \"bbd94b7fc2fe75398802807841a03d63fd996e37\", \"compositor_unittests\": \"76481d7282a04ae1e980aed0420daabcde9ebc62\", \"content_browsertests\": \"4a275e51a77d380e320880b79ab9b27535cd3eb6\", \"content_unittests\": \"b610348813e8444640b79247c941766c90bfb8d8\", \"courgette_unittests\": \"c288fc7c61166927e19f959000629d4d7fd30ed1\", \"crashpad_tests\": \"3f593d2091d3f090e9f5ffc81722354dadc99870\", \"cronet_tests\": \"fd4b54768ef283410acd12ba2e352cecfa736bf8\", \"cronet_unittests\": \"cf53cfcd814739b9e25d0072d4ada89ebc04f8e2\", \"crypto_unittests\": \"74aabeb4e8a5d82b5c9362be755f21a3f8d93ef8\", \"delayloads_unittests\": \"85158ae70f900f63f43de32703d894108116e097\", \"device_unittests\": \"4e89aff1684ac8a6ba0e5b6f06e02228f2733df4\", \"display_unittests\": \"425aa863fd98caee6d7c9a3c10d1475b498bdc9f\", \"elevation_service_unittests\": \"1df852e084e17a8a56e6fa8fb640ebac3230f3fb\", \"events_unittests\": \"20c1bb2c2204d80bb80cdb678b0b0732e58ee858\", \"extensions_browsertests\": \"335d91137430241feee997856d1882dce814d609\", \"extensions_unittests\": \"763699060e763f65329bb5b6c860c55f2ca33592\", \"filesystem_service_unittests\": \"0bb922fdfa67158fb42e845e7b6363ef5edcde9f\", \"gcm_unit_tests\": \"098d9ced3dc0575dfd3fd11ac3e5a9f3cd44c868\", \"gcp_unittests\": \"98ebe4f44242345a9ce4ed996691cf30153306f5\", \"gfx_unittests\": \"ccb7bcfda1b42bd485438b3f58ebf890d646eb02\", \"gin_unittests\": \"9c82d02389644f25500ac3d446e799f39e674336\", \"google_apis_unittests\": \"300c291ecd6aadb7d17d83b2aa19b34a0d298097\", \"gpu_unittests\": \"15c5dc20ec0548b19dfe2d13d7e45752d183b2fd\", \"gwp_asan_unittests\": \"b6e4063b14de943ae58fc9c60caa442bc8b32019\", \"headless_browsertests\": \"9b6eb6168ad748adb042d598f045118bfc727032\", \"headless_unittests\": \"2fef5efba01e058b4837c7dd837674405cb02164\", \"install_static_unittests\": \"06db233e86a25baeb1c7ea71a4629acbdf5bed7f\", \"installer_util_unittests\": \"b8500624332eed2fa77926ea770f4fc4bf32ae4b\", \"interactive_ui_tests\": \"1d7bb644d6eecd2d393508a2bef7eea4aeb8f40b\", \"ipc_tests\": \"38f3f26dff5b94b8b30566dcc80a707a743bb3eb\", \"jingle_unittests\": \"bd135b02948f837c8666e2cf35054db13863b7fe\", \"latency_unittests\": \"21b29e455a3965abbf0977a025a0eb79c59c1f16\", \"libjingle_xmpp_unittests\": \"e48970b837fc5215012645b5424fdcd291dfbc67\", \"liburlpattern_unittests\": \"88274f659abb36b98f8d60464addc7e4e2cc4f78\", \"media_blink_unittests\": \"b573c092625adbf826def5eaa7f505e627842233\", \"media_unittests\": \"0ca9d843cd8a01aa44d5703fd26823e3122b8c0b\", \"message_center_unittests\": \"70012fc47b6aaed7df50dd11a2f73d42c61bc09d\", \"midi_unittests\": \"61264388c070582539e21f120d15a5f160d86449\", \"mojo_core_unittests\": \"5b1e1b33f2d8c7a5e4a129cffcf12bb5d76e685f\", \"mojo_unittests\": \"49552dd59731280f9a7c3810be0076116758d713\", \"nacl_loader_unittests\": \"7ca4deef0e5ccb340b89171ecccb6c37bbe96e25\", \"native_theme_unittests\": \"d3067a2b9fffcdd60bd1bcd9bad005403d56d4ca\", \"net_unittests\": \"a02d07dd15ec9ae8a090a47397987cbee4532def\", \"notification_helper_unittests\": \"6dc9e825b79ef164ed9a84fa5d65b361bec41e4a\", \"pdf_unittests\": \"eef38c173c2254c822110d80de08e43f6d63bdae\", \"perfetto_unittests\": \"dad0618bcb4ba9ba877af241aad27f3d1c51bba4\", \"ppapi_unittests\": \"435556032e01b805c1647d293166e34f31f2dab5\", \"printing_unittests\": \"97b16ff199d5ce5eaf636e960e96fb23d63619a3\", \"remoting_unittests\": \"c6907b29f14f11bd139e7f22f822aac2cd649cf8\", \"sbox_integration_tests\": \"1ebb7ca5a032d1cd0fceb4e5d5e10329697f8053\", \"sbox_unittests\": \"e50c11df3098a873aa69218b1e518d800165864e\", \"sbox_validation_tests\": \"927cc55ff0c14b238069e83c2b212405e2ee3cd3\", \"service_manager_unittests\": \"37c52de49af0a7a5a3a66f4885a5ccdd1b8d49be\", \"services_unittests\": \"f84aba7b08aa68ed0cb3ddd2dd0311c7407cffb6\", \"setup_unittests\": \"8705a55b69629af71446cc15919df6220fa51027\", \"shell_dialogs_unittests\": \"b941ed7ed0b4b3dc92d643ccdda6c29ff2d3e103\", \"skia_unittests\": \"e00949d9e7ddb459db40dd5fe66d4ff04624e284\", \"snapshot_unittests\": \"673e48c888b624c0f040b194128ac8e2752ba7cd\", \"sql_unittests\": \"b8e101946a61216829109025f013108168458553\", \"storage_unittests\": \"a4aef133478473b57fcfc2c4b0d09c63a4cb976c\", \"sync_integration_tests\": \"cc9ac807f8bd45c81be03c169a76b4b570522739\", \"traffic_annotation_auditor_unittests\": \"740bff8f46b0f8a1fd25348cd8c666972b6b5eda\", \"ui_base_unittests\": \"7967776ef52f4bee6286d6b70de210684a9453de\", \"ui_touch_selection_unittests\": \"e9aea93dd394869285b449280e041f9ef1b00ac4\", \"unit_tests\": \"0d27ed47c04fcea77c5ff724ddbddbf7fab3170b\", \"updater_tests\": \"32f242b66aecf5c2130153059f8288b28eeba0ea\", \"url_unittests\": \"8dfe34b466e5d23e8cf04880d23f8fe3df5f4c7d\", \"views_unittests\": \"1156e88f3b90783bcefc8665643c92f7c4ec2e3d\", \"viz_unittests\": \"bd9e9dc3581898f4b4f71c8bcf10db5ee44c96f7\", \"vr_common_unittests\": \"b3be108259377441e27d7fee12c30983fda81d65\", \"vr_pixeltests\": \"84e929710daa408eaa842011ca8998b05075a8cb\", \"weblayer_browsertests\": \"35bbf9e76219e7b2510fa4f20a4ff8f6ba158572\", \"weblayer_unittests\": \"b7bb24fe1e91da6af36fa2488f7d231e01fcb8ae\", \"wm_unittests\": \"50bc30f56797025f890d178567c8bf77d963d916\", \"wtf_unittests\": \"16c212a29ab191d9807475ffce265b5919feea91\", \"zlib_unittests\": \"f86193f8ab784eec63266b150412ddbe5f48196f\", \"zucchini_unittests\": \"81f6f3d58204784e08040b8c04dfac071215dafd\"}}, \"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-8-6zek\"], \"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\": [\"5635-1756fdb\"], \"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": "1605701364059651",
"status": "COMPLETED",
"status_changed_ts": "1605704905023919",
"tags": [
"build_address:luci.chromium.ci/win-asan/15704",
"builder:win-asan",
"buildset:commit/git/c780b01e35977685e78c948c6badc46ccd0e6333",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c780b01e35977685e78c948c6badc46ccd0e6333",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028723866691282416",
"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/8863293804338400352/+/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:4ff413eda89ba710",
"user_agent:luci-scheduler"
],
"updated_ts": "1605704905035462",
"url": "https://ci.chromium.org/b/8863293804338400352",
"utcnow_ts": "1605704905959219"
}
}
[13:08:25.967] Invocation finished in 59m5.346952992s with status SUCCEEDED