[03:02:22.607] New invocation is queued and will start shortly
[03:02:23.692] Starting the invocation (attempt 1)
[03:02:23.729] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:23.729] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:23.729] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018883209551675264",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"20f0d7747a903c72f89a77f0c436fab41997e2cd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018883209551675264",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"gitiles_ref:refs/heads/master"
]
}
[03:02:25.502] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615086143818509",
"id": "8853453147617851568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"20f0d7747a903c72f89a77f0c436fab41997e2cd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615086144371088",
"tags": [
"build_address:luci.chromium.ci/win-asan/17675",
"builder:win-asan",
"buildset:commit/git/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018883209551675264",
"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/8853453147617851568/+/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": "1615086144371183",
"url": "https://ci.chromium.org/b/8853453147617851568",
"utcnow_ts": "1615086145496146"
}
}
[03:02:25.502] Task URL: https://ci.chromium.org/b/8853453147617851568
[03:02:25.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:2:0) after 1m0s
[03:02:29.317] Received PubSub notification, asking Buildbucket for the build status
[03:02:29.609] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:25.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:2:0)
[03:03:25.520] Timer tick, asking Buildbucket for the build status
[03:03:25.584] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:25.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:4:0) after 1m0s
[03:04:25.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:4:0)
[03:04:25.681] Timer tick, asking Buildbucket for the build status
[03:04:25.765] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:25.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:5:0) after 1m0s
[03:05:25.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:5:0)
[03:05:25.804] Timer tick, asking Buildbucket for the build status
[03:05:25.867] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:25.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:6:0) after 1m0s
[03:06:25.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:6:0)
[03:06:25.966] Timer tick, asking Buildbucket for the build status
[03:06:26.207] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:26.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:7:0) after 1m0s
[03:07:26.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:7:0)
[03:07:26.232] Timer tick, asking Buildbucket for the build status
[03:07:26.352] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:26.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:8:0) after 1m0s
[03:08:26.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:8:0)
[03:08:26.402] Timer tick, asking Buildbucket for the build status
[03:08:26.515] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:26.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:9:0) after 1m0s
[03:09:26.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:9:0)
[03:09:26.503] Timer tick, asking Buildbucket for the build status
[03:09:26.739] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:26.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:10:0) after 1m0s
[03:10:26.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:10:0)
[03:10:26.746] Timer tick, asking Buildbucket for the build status
[03:10:26.896] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:26.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:11:0) after 1m0s
[03:11:26.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:11:0)
[03:11:26.975] Timer tick, asking Buildbucket for the build status
[03:11:27.201] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:27.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:12:0) after 1m0s
[03:12:27.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:12:0)
[03:12:27.719] Timer tick, asking Buildbucket for the build status
[03:12:27.805] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:27.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:13:0) after 1m0s
[03:13:27.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:13:0)
[03:13:27.820] Timer tick, asking Buildbucket for the build status
[03:13:27.905] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:27.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:14:0) after 1m0s
[03:14:27.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:14:0)
[03:14:27.919] Timer tick, asking Buildbucket for the build status
[03:14:28.114] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:28.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:15:0) after 1m0s
[03:15:28.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:15:0)
[03:15:28.131] Timer tick, asking Buildbucket for the build status
[03:15:28.337] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:28.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:16:0) after 1m0s
[03:16:28.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:16:0)
[03:16:28.380] Timer tick, asking Buildbucket for the build status
[03:16:28.444] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:28.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:17:0) after 1m0s
[03:17:28.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:17:0)
[03:17:28.460] Timer tick, asking Buildbucket for the build status
[03:17:28.546] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:28.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:18:0) after 1m0s
[03:18:28.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:18:0)
[03:18:28.535] Timer tick, asking Buildbucket for the build status
[03:18:28.707] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:28.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:19:0) after 1m0s
[03:19:28.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:19:0)
[03:19:28.796] Timer tick, asking Buildbucket for the build status
[03:19:28.943] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:28.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:20:0) after 1m0s
[03:20:28.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:20:0)
[03:20:28.970] Timer tick, asking Buildbucket for the build status
[03:20:29.102] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:29.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:21:0) after 1m0s
[03:21:29.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:21:0)
[03:21:29.109] Timer tick, asking Buildbucket for the build status
[03:21:29.318] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:29.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:22:0) after 1m0s
[03:22:29.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:22:0)
[03:22:29.369] Timer tick, asking Buildbucket for the build status
[03:22:29.517] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:29.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:23:0) after 1m0s
[03:23:29.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:23:0)
[03:23:29.577] Timer tick, asking Buildbucket for the build status
[03:23:29.656] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:29.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:24:0) after 1m0s
[03:24:29.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:24:0)
[03:24:29.665] Timer tick, asking Buildbucket for the build status
[03:24:29.827] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:29.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:25:0) after 1m0s
[03:25:29.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:25:0)
[03:25:29.811] Timer tick, asking Buildbucket for the build status
[03:25:29.897] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:29.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:26:0) after 1m0s
[03:26:29.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:26:0)
[03:26:29.963] Timer tick, asking Buildbucket for the build status
[03:26:30.208] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:30.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:27:0) after 1m0s
[03:27:30.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:27:0)
[03:27:30.235] Timer tick, asking Buildbucket for the build status
[03:27:30.705] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:30.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:28:0) after 1m0s
[03:28:30.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:28:0)
[03:28:30.807] Timer tick, asking Buildbucket for the build status
[03:28:31.026] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:31.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:29:0) after 1m0s
[03:29:31.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:29:0)
[03:29:31.101] Timer tick, asking Buildbucket for the build status
[03:29:31.200] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:31.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:30:0) after 1m0s
[03:30:31.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:30:0)
[03:30:31.241] Timer tick, asking Buildbucket for the build status
[03:30:31.401] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:31.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:31:0) after 1m0s
[03:31:31.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:31:0)
[03:31:31.456] Timer tick, asking Buildbucket for the build status
[03:31:31.542] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:31.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:32:0) after 1m0s
[03:32:31.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:32:0)
[03:32:31.610] Timer tick, asking Buildbucket for the build status
[03:32:31.816] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:31.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:33:0) after 1m0s
[03:33:31.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:33:0)
[03:33:31.854] Timer tick, asking Buildbucket for the build status
[03:33:31.932] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:34:0) after 1m0s
[03:34:31.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:34:0)
[03:34:31.957] Timer tick, asking Buildbucket for the build status
[03:34:32.263] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:32.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:35:0) after 1m0s
[03:35:32.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:35:0)
[03:35:32.296] Timer tick, asking Buildbucket for the build status
[03:35:32.372] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:32.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:36:0) after 1m0s
[03:36:32.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:36:0)
[03:36:32.417] Timer tick, asking Buildbucket for the build status
[03:36:32.541] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:32.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:37:0) after 1m0s
[03:37:32.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:37:0)
[03:37:32.591] Timer tick, asking Buildbucket for the build status
[03:37:32.690] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:38:0) after 1m0s
[03:38:32.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:38:0)
[03:38:32.958] Timer tick, asking Buildbucket for the build status
[03:38:33.032] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:33.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:39:0) after 1m0s
[03:39:33.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:39:0)
[03:39:33.263] Timer tick, asking Buildbucket for the build status
[03:39:33.369] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:33.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:40:0) after 1m0s
[03:40:33.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:40:0)
[03:40:33.365] Timer tick, asking Buildbucket for the build status
[03:40:33.436] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:33.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:41:0) after 1m0s
[03:41:33.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:41:0)
[03:41:33.571] Timer tick, asking Buildbucket for the build status
[03:41:33.650] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:33.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:42:0) after 1m0s
[03:42:33.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:42:0)
[03:42:33.676] Timer tick, asking Buildbucket for the build status
[03:42:33.769] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:33.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:43:0) after 1m0s
[03:43:33.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:43:0)
[03:43:33.794] Timer tick, asking Buildbucket for the build status
[03:43:34.110] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:34.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:44:0) after 1m0s
[03:44:34.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:44:0)
[03:44:34.133] Timer tick, asking Buildbucket for the build status
[03:44:34.208] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:34.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:45:0) after 1m0s
[03:45:34.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:45:0)
[03:45:34.211] Timer tick, asking Buildbucket for the build status
[03:45:34.514] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:34.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:46:0) after 1m0s
[03:46:34.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:46:0)
[03:46:34.560] Timer tick, asking Buildbucket for the build status
[03:46:34.958] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:34.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:47:0) after 1m0s
[03:47:35.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:47:0)
[03:47:35.166] Timer tick, asking Buildbucket for the build status
[03:47:35.269] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:35.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:48:0) after 1m0s
[03:48:35.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:48:0)
[03:48:35.449] Timer tick, asking Buildbucket for the build status
[03:48:35.818] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:35.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:49:0) after 1m0s
[03:49:36.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:49:0)
[03:49:36.023] Timer tick, asking Buildbucket for the build status
[03:49:36.310] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:36.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:50:0) after 1m0s
[03:50:36.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:50:0)
[03:50:36.405] Timer tick, asking Buildbucket for the build status
[03:50:36.478] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:36.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:51:0) after 1m0s
[03:51:36.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:51:0)
[03:51:36.674] Timer tick, asking Buildbucket for the build status
[03:51:36.746] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:36.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:52:0) after 1m0s
[03:52:36.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:52:0)
[03:52:36.743] Timer tick, asking Buildbucket for the build status
[03:52:37.139] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:37.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:53:0) after 1m0s
[03:53:37.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:53:0)
[03:53:37.395] Timer tick, asking Buildbucket for the build status
[03:53:37.498] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:37.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:54:0) after 1m0s
[03:54:37.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:54:0)
[03:54:37.751] Timer tick, asking Buildbucket for the build status
[03:54:37.823] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:37.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:55:0) after 1m0s
[03:55:37.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:55:0)
[03:55:37.839] Timer tick, asking Buildbucket for the build status
[03:55:37.905] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:37.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:56:0) after 1m0s
[03:56:38.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:56:0)
[03:56:38.119] Timer tick, asking Buildbucket for the build status
[03:56:38.253] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:38.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:57:0) after 1m0s
[03:57:38.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:57:0)
[03:57:38.268] Timer tick, asking Buildbucket for the build status
[03:57:38.714] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:38.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:58:0) after 1m0s
[03:58:40.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:58:0)
[03:58:40.691] Timer tick, asking Buildbucket for the build status
[03:58:40.780] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:40.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:59:0) after 1m0s
[03:59:40.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:59:0)
[03:59:40.799] Timer tick, asking Buildbucket for the build status
[03:59:40.881] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:60:0) after 1m0s
[04:00:40.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:60:0)
[04:00:40.952] Timer tick, asking Buildbucket for the build status
[04:00:41.122] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:61:0) after 1m0s
[04:01:41.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:61:0)
[04:01:41.124] Timer tick, asking Buildbucket for the build status
[04:01:41.200] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:41.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:62:0) after 1m0s
[04:02:41.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:62:0)
[04:02:41.274] Timer tick, asking Buildbucket for the build status
[04:02:41.691] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:41.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:63:0) after 1m0s
[04:03:41.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:63:0)
[04:03:41.731] Timer tick, asking Buildbucket for the build status
[04:03:41.814] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:41.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:64:0) after 1m0s
[04:04:41.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:64:0)
[04:04:41.868] Timer tick, asking Buildbucket for the build status
[04:04:42.040] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:42.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:65:0) after 1m0s
[04:05:42.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:65:0)
[04:05:42.063] Timer tick, asking Buildbucket for the build status
[04:05:42.160] Build 8853453147617851568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:42.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018883209551675264:66:0) after 1m0s
[04:06:30.325] Received PubSub notification, asking Buildbucket for the build status
[04:06:30.405] Build 8853453147617851568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:06:30.406] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615089988603720",
"created_by": "project:chromium",
"created_ts": "1615086143818509",
"id": "8853453147617851568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"20f0d7747a903c72f89a77f0c436fab41997e2cd\"}}",
"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\": \"20f0d7747a903c72f89a77f0c436fab41997e2cd\", \"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-ux1a\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17675, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-1\", \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"20f0d7747a903c72f89a77f0c436fab41997e2cd\", \"got_revision_cp\": \"refs/heads/master@{#860569}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"f8b883fbba96b155409eb00da93826216bea9fc0\", \"got_v8_revision_cp\": \"refs/heads/9.1.60@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"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\": \"20f0d7747a903c72f89a77f0c436fab41997e2cd\", \"swarm_hashes_refs/heads/master(at){#860569}_without_patch\": {\"absl_hardening_tests\": \"b782696fb8223ba9ce9480599f36ed2396cfec37\", \"accessibility_unittests\": \"f4e489cb3233b67cc56b83c573eaaba3374f49f2\", \"angle_unittests\": \"8d7d76ff40c51712a48256aecadf7d5cd6374f4a\", \"app_shell_unittests\": \"a2a3268d8d98593ab8b893fdc50330bae2cb1c0d\", \"aura_unittests\": \"d0dcb767dba4c0bff14731ef09c004d2884ec9cb\", \"base_unittests\": \"83c7994353daeb2bbcfffbff2563e3a8ac62c2ed\", \"base_util_unittests\": \"84021d7d984ac89894bf3b4b425fd61d11d80b16\", \"blink_common_unittests\": \"b2698e32825af5bf0db5c375de78f40aa22bbbb5\", \"blink_fuzzer_unittests\": \"7a2cae5754bd97b3c3e1dece8f48de8937f0605d\", \"blink_heap_unittests\": \"b26740cfd6aaa08775ab4363eb5431a9f84da3e9\", \"blink_platform_unittests\": \"0697629e9f7826ca29ff623afef1bb9024ccfb38\", \"blink_unittests\": \"eedd0ab6feef466b2f76b3ceee9dc338e29881de\", \"boringssl_crypto_tests\": \"a2bd188a44e5c7a9d7cca943e92465352fd00819\", \"boringssl_ssl_tests\": \"89761c2e1196ef39823eb7ac543f30234dc1bbe4\", \"browser_switcher_bho_unittests\": \"e1a67ec3e03b4ecec764702ec504b4e6181c40d3\", \"browser_tests\": \"feea827ba2d584a3845a65d18efd7eb7fbb418e6\", \"capture_unittests\": \"503fba3f1c370ba0bbec6b81f7cceb13e352116f\", \"cast_unittests\": \"13f9717ae58d7729ac017172bb4b254b7ade280d\", \"cc_unittests\": \"4f9d4f89f5291df254fd7e85cdc8cc2f75b6ccf0\", \"chrome_app_unittests\": \"6f4a16f21a4eaf3c2b5c116d75660a3d9632d8fd\", \"chrome_cleaner_unittests\": \"e0f003f320e06db26ea4e65382d73aaa3f5584b5\", \"chrome_elf_unittests\": \"1ad98aa4c350e4b58e6c7955e82634bac420b5e8\", \"chromedriver_unittests\": \"6224d748e80962d50fb5e7080d410165ed3a00f3\", \"color_unittests\": \"b234fb044fffd9f5f73a790e104101c6c7ea515b\", \"components_browsertests\": \"8d1749297241ba58ffc822ee5a015dce145989f3\", \"components_unittests\": \"1b5cc1b90d4f4d01cb5f0aa44c71ac46352ca44c\", \"compositor_unittests\": \"fe6067ebb98004bb133362ae05734d59b259d6c4\", \"content_browsertests\": \"1290c49ba9b4f57be8ab167756487b2d064f4830\", \"content_unittests\": \"ef35e1ff175c394393d25991098f6ff0fb2ee7b9\", \"courgette_unittests\": \"f4287f1cea7a06a79b6fa2eaf143b47348d04464\", \"crashpad_tests\": \"520eb5e1c5a1318c4bf63129f160b7fe505ff287\", \"cronet_tests\": \"68d8fb8c274f84742c7a402670c566db93ebc23e\", \"cronet_unittests\": \"92a7f809df192360b73ff96fd43919964af48ec7\", \"crypto_unittests\": \"f17275d98cba2dea1d12883d145c41bd3465565f\", \"delayloads_unittests\": \"ab4bf8e9bcf7a89ce54bbe477c8d34acbfd32ad8\", \"device_unittests\": \"faf6d87cf9f91ab15df76063e3c65186f8f1bd27\", \"display_unittests\": \"22985a41d106b6cf1bbb52479090d78f7fd3d648\", \"elevation_service_unittests\": \"b0cd419b19c4dd32d1c1c7a78a53f23a1e38dee9\", \"events_unittests\": \"476a04438d335cbf01256a96307cd3e2033cdc11\", \"extensions_browsertests\": \"61bbc653e8bfcade1c6ea0f024d4e91aa09d54f7\", \"extensions_unittests\": \"0d4201f4f1465b16eca187208b4fd4db3ff67744\", \"filesystem_service_unittests\": \"81e17d4a8204a8d857d385c4d2a83d823e73961d\", \"gcm_unit_tests\": \"73e9d01838c60a00360f67b75862343bb4d93438\", \"gcp_unittests\": \"98716422ca11763d660b835acc9f740397f8d5bb\", \"gfx_unittests\": \"430ff0ffdbf9373ce4713b2cca6dce24201633d6\", \"gin_unittests\": \"855c920e9a3ac8692404dea35d2f8890a9deb077\", \"google_apis_unittests\": \"ba5ce5f947718a63acff45b1d10557259cfb65e6\", \"gpu_unittests\": \"8eec5393944bf53190c7f7684ed0adf34d91aabe\", \"gwp_asan_unittests\": \"6e76bc88d8bf7f868800a38cc8ae5b6c701086f9\", \"headless_browsertests\": \"782cbe4e1b257605a8917817588fa98073a747e2\", \"headless_unittests\": \"faeeadedbde321525bac4cf1748b5f2e08409380\", \"install_static_unittests\": \"f5c642d03aac7d351cd47561595f8044adc9e381\", \"installer_util_unittests\": \"cb698a2758a509b47c49b2aacb59a9a9217b68b1\", \"interactive_ui_tests\": \"35edf388f2680eb699ecd919bcaa1870beaba4fa\", \"ipc_tests\": \"852433e9ad52891e4cf897c75bdf8e1eb112b39e\", \"jingle_unittests\": \"7ff7da48209825514f02c7a2b2d10df34d466e90\", \"latency_unittests\": \"83c35425dff5bfb8f3588a55ce7f4220474c7047\", \"libjingle_xmpp_unittests\": \"52c1dd6785f881e4f0d6578296e72efff128af6b\", \"liburlpattern_unittests\": \"a3a8da9370663d911400e9beb583995e6b0cb77d\", \"media_blink_unittests\": \"a09dfc1fd73dc2e8d98080a26e35d3a0ed089126\", \"media_unittests\": \"512c953d3fda98a5ba85b48b777412fd909b431b\", \"message_center_unittests\": \"2fe5caba115a74e88094cca352a9b91888b87a4a\", \"midi_unittests\": \"ab01bf1ee343d41b04476c7386d160287bb957a4\", \"mojo_core_unittests\": \"a015e6bf8a265065d4939e630502119b26efb8c8\", \"mojo_unittests\": \"d6a0155482a818f28d1dadac2a01fee4682aa617\", \"nacl_loader_unittests\": \"e08d307d6b5f204f3bbfeca733ae559f9a18afa1\", \"native_theme_unittests\": \"a9fa6da6e9300dc308d58e5427ef4dab99e0b7bc\", \"net_unittests\": \"1673b0fcecd67f49c1e464eced0c3e3145fa610c\", \"notification_helper_unittests\": \"bd9889258df6a717a3560789a6bc7a79ff8944c5\", \"pdf_unittests\": \"33fac7a8085358b07a4cb837ff9813ea20df7374\", \"perfetto_unittests\": \"2e6d429683dfc8f70fc3cf397503b220369b9af2\", \"ppapi_unittests\": \"b419ad7b88071a6a0c97098ec870c6ac07584f9e\", \"printing_unittests\": \"9e086f528771a0628ca31969c7d283fd6b7f7d68\", \"remoting_unittests\": \"7411bc01d5d5ad47dadd92263da171911a927282\", \"sbox_integration_tests\": \"d1c5e124914a1c05a77f10aa8e6fad51f153fd04\", \"sbox_unittests\": \"671712d9716737cd2d333c864808f6979cfd1ae8\", \"sbox_validation_tests\": \"f32a460c7c6255fa406a064c02249b548094da0d\", \"service_manager_unittests\": \"1a7b88da3046a9879ef57c8557091af0ca820a4f\", \"services_unittests\": \"4f84b915d9a7cb2ac565f34c12a383675f4cda6b\", \"setup_unittests\": \"aa8fbd181f398ad3186abc40a8f65c49345ffb1c\", \"shell_dialogs_unittests\": \"86e6dcfb6a807e4130a090f34c5b7d08dd70af23\", \"skia_unittests\": \"0d164957c37b870bca6f624e601b9ff1995d8c4c\", \"snapshot_unittests\": \"ccf35e01a5f8b286e9977e3591c3e5ac875e72dd\", \"sql_unittests\": \"116ff652601a425efc4d230b14d83df5f73bcf2c\", \"storage_unittests\": \"219271ae89d4a3f453bab1f0a5561995b76b6b38\", \"sync_integration_tests\": \"5b1581496d0031f01c792e18d80d15de5612d29d\", \"traffic_annotation_auditor_unittests\": \"91e62b29eeecaa78aa9a2a13fdf555619e8d35ad\", \"ui_base_unittests\": \"bffd2f4bf36bd73b94c8bfed1939c1e445e8b893\", \"ui_touch_selection_unittests\": \"68b1ca15e5f7d0147de7b3cc9c68ff522f58d08c\", \"unit_tests\": \"74209482c55902002143cd7152a59aee0f159ba8\", \"updater_tests\": \"a3462ded7631778d4f138b5b4bdbb4cc08bc1654\", \"url_unittests\": \"deee53c1fbdf55e0b04524457d49958691a08cbf\", \"views_unittests\": \"c76a84b7cb2adda187c28095e05f56080ab5b7b4\", \"viz_unittests\": \"05e7da454f804b8d86e2bcc7d80f5ce8600afc7f\", \"vr_common_unittests\": \"273beca6a1af4552bc830c692f06c137be5c8db4\", \"vr_pixeltests\": \"bb6cc3ea2c6201367026bc2bdd46c0f873c89f31\", \"weblayer_browsertests\": \"c472a88293bfcc94fc635912814e37ba2d256e95\", \"weblayer_unittests\": \"d8da4050ab2894d117365138681875d74dc05a0f\", \"wm_unittests\": \"f40f7d945c49b5f3f2f2f5c6b76a3904cba864ff\", \"wtf_unittests\": \"d92818be0cbe522494fa475bdaa4f59bc65b2f4e\", \"zlib_unittests\": \"97fd272658e022b9f56250326d9b8e5a6f7a2967\", \"zucchini_unittests\": \"351f80897d726d8bda26ea23f543ef7b39725a00\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"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-ux1a\"], \"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\": [\"5725-1a0d85e\"], \"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": "1615086148595278",
"status": "COMPLETED",
"status_changed_ts": "1615089989133308",
"tags": [
"build_address:luci.chromium.ci/win-asan/17675",
"builder:win-asan",
"buildset:commit/git/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20f0d7747a903c72f89a77f0c436fab41997e2cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018883209551675264",
"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/8853453147617851568/+/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:5223745f2d93a910",
"user_agent:luci-scheduler"
],
"updated_ts": "1615089989147820",
"url": "https://ci.chromium.org/b/8853453147617851568",
"utcnow_ts": "1615089990397326"
}
}
[04:06:30.406] Invocation finished in 1h4m7.814274939s with status SUCCEEDED