[08:38:28.692] New invocation is queued and will start shortly
[08:38:29.977] Starting the invocation (attempt 1)
[08:38:30.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:38:30.033] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:38:30.033] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021489375963988560",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"da1861760cfe68a9177503fb39d828a83b21b09d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021489375963988560",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/da1861760cfe68a9177503fb39d828a83b21b09d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da1861760cfe68a9177503fb39d828a83b21b09d",
"gitiles_ref:refs/heads/master"
]
}
[08:38:31.238] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612600710130688",
"id": "8856059313732739376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"da1861760cfe68a9177503fb39d828a83b21b09d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612600710915723",
"tags": [
"build_address:luci.chromium.ci/win-asan/17197",
"builder:win-asan",
"buildset:commit/git/da1861760cfe68a9177503fb39d828a83b21b09d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da1861760cfe68a9177503fb39d828a83b21b09d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021489375963988560",
"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/8856059313732739376/+/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": "1612600710916169",
"url": "https://ci.chromium.org/b/8856059313732739376",
"utcnow_ts": "1612600711220042"
}
}
[08:38:31.239] Task URL: https://ci.chromium.org/b/8856059313732739376
[08:38:31.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:2:0) after 1m0s
[08:38:34.124] Received PubSub notification, asking Buildbucket for the build status
[08:38:34.206] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:31.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:2:0)
[08:39:31.255] Timer tick, asking Buildbucket for the build status
[08:39:31.328] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:31.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:4:0) after 1m0s
[08:40:31.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:4:0)
[08:40:31.371] Timer tick, asking Buildbucket for the build status
[08:40:31.512] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:31.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:5:0) after 1m0s
[08:41:31.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:5:0)
[08:41:31.559] Timer tick, asking Buildbucket for the build status
[08:41:32.390] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:32.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:6:0) after 1m0s
[08:42:32.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:6:0)
[08:42:32.453] Timer tick, asking Buildbucket for the build status
[08:42:32.930] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:32.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:7:0) after 1m0s
[08:43:33.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:7:0)
[08:43:33.020] Timer tick, asking Buildbucket for the build status
[08:43:33.148] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:33.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:8:0) after 1m0s
[08:44:33.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:8:0)
[08:44:33.243] Timer tick, asking Buildbucket for the build status
[08:44:33.607] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:33.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:9:0) after 1m0s
[08:45:33.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:9:0)
[08:45:33.634] Timer tick, asking Buildbucket for the build status
[08:45:33.716] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:33.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:10:0) after 1m0s
[08:46:33.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:10:0)
[08:46:33.747] Timer tick, asking Buildbucket for the build status
[08:46:33.811] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:33.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:11:0) after 1m0s
[08:47:33.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:11:0)
[08:47:33.904] Timer tick, asking Buildbucket for the build status
[08:47:33.963] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:33.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:12:0) after 1m0s
[08:48:34.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:12:0)
[08:48:34.000] Timer tick, asking Buildbucket for the build status
[08:48:34.150] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:34.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:13:0) after 1m0s
[08:49:34.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:13:0)
[08:49:34.181] Timer tick, asking Buildbucket for the build status
[08:49:34.271] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:34.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:14:0) after 1m0s
[08:50:34.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:14:0)
[08:50:34.306] Timer tick, asking Buildbucket for the build status
[08:50:34.452] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:34.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:15:0) after 1m0s
[08:51:34.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:15:0)
[08:51:34.467] Timer tick, asking Buildbucket for the build status
[08:51:34.533] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:34.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:16:0) after 1m0s
[08:52:34.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:16:0)
[08:52:34.587] Timer tick, asking Buildbucket for the build status
[08:52:34.647] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:34.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:17:0) after 1m0s
[08:53:34.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:17:0)
[08:53:34.755] Timer tick, asking Buildbucket for the build status
[08:53:34.950] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:34.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:18:0) after 1m0s
[08:54:35.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:18:0)
[08:54:35.075] Timer tick, asking Buildbucket for the build status
[08:54:35.242] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:19:0) after 1m0s
[08:55:35.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:19:0)
[08:55:35.245] Timer tick, asking Buildbucket for the build status
[08:55:35.315] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:35.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:20:0) after 1m0s
[08:56:35.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:20:0)
[08:56:35.365] Timer tick, asking Buildbucket for the build status
[08:56:35.698] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:35.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:21:0) after 1m0s
[08:57:35.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:21:0)
[08:57:35.748] Timer tick, asking Buildbucket for the build status
[08:57:36.201] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:36.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:22:0) after 1m0s
[08:58:36.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:22:0)
[08:58:36.200] Timer tick, asking Buildbucket for the build status
[08:58:36.695] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:36.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:23:0) after 1m0s
[08:59:36.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:23:0)
[08:59:36.712] Timer tick, asking Buildbucket for the build status
[08:59:37.725] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:37.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:24:0) after 1m0s
[09:00:37.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:24:0)
[09:00:37.781] Timer tick, asking Buildbucket for the build status
[09:00:38.133] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:38.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:25:0) after 1m0s
[09:01:38.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:25:0)
[09:01:38.155] Timer tick, asking Buildbucket for the build status
[09:01:38.260] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:38.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:26:0) after 1m0s
[09:02:38.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:26:0)
[09:02:38.250] Timer tick, asking Buildbucket for the build status
[09:02:38.515] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:38.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:27:0) after 1m0s
[09:03:38.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:27:0)
[09:03:38.645] Timer tick, asking Buildbucket for the build status
[09:03:39.080] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:39.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:28:0) after 1m0s
[09:04:39.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:28:0)
[09:04:39.126] Timer tick, asking Buildbucket for the build status
[09:04:39.393] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:39.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:29:0) after 1m0s
[09:05:39.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:29:0)
[09:05:39.449] Timer tick, asking Buildbucket for the build status
[09:05:39.623] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:39.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:30:0) after 1m0s
[09:06:39.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:30:0)
[09:06:39.769] Timer tick, asking Buildbucket for the build status
[09:06:40.446] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:31:0) after 1m0s
[09:07:40.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:31:0)
[09:07:40.546] Timer tick, asking Buildbucket for the build status
[09:07:40.661] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:40.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:32:0) after 1m0s
[09:08:40.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:32:0)
[09:08:40.757] Timer tick, asking Buildbucket for the build status
[09:08:40.869] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:33:0) after 1m0s
[09:09:40.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:33:0)
[09:09:40.892] Timer tick, asking Buildbucket for the build status
[09:09:41.219] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:41.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:34:0) after 1m0s
[09:10:41.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:34:0)
[09:10:41.244] Timer tick, asking Buildbucket for the build status
[09:10:41.371] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:41.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:35:0) after 1m0s
[09:11:41.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:35:0)
[09:11:41.384] Timer tick, asking Buildbucket for the build status
[09:11:41.719] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:36:0) after 1m0s
[09:12:41.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:36:0)
[09:12:41.740] Timer tick, asking Buildbucket for the build status
[09:12:41.821] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:37:0) after 1m0s
[09:13:41.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:37:0)
[09:13:41.841] Timer tick, asking Buildbucket for the build status
[09:13:42.018] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:42.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:38:0) after 1m0s
[09:14:42.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:38:0)
[09:14:42.038] Timer tick, asking Buildbucket for the build status
[09:14:42.243] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:42.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:39:0) after 1m0s
[09:15:42.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:39:0)
[09:15:42.219] Timer tick, asking Buildbucket for the build status
[09:15:42.501] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:42.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:40:0) after 1m0s
[09:16:42.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:40:0)
[09:16:42.543] Timer tick, asking Buildbucket for the build status
[09:16:42.704] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:41:0) after 1m0s
[09:17:42.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:41:0)
[09:17:42.733] Timer tick, asking Buildbucket for the build status
[09:17:43.412] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:43.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:42:0) after 1m0s
[09:18:43.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:42:0)
[09:18:43.431] Timer tick, asking Buildbucket for the build status
[09:18:43.891] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:43.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:43:0) after 1m0s
[09:19:43.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:43:0)
[09:19:43.908] Timer tick, asking Buildbucket for the build status
[09:19:44.759] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:44.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:44:0) after 1m0s
[09:20:44.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:44:0)
[09:20:44.730] Timer tick, asking Buildbucket for the build status
[09:20:45.151] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:45.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:45:0) after 1m0s
[09:21:45.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:45:0)
[09:21:45.185] Timer tick, asking Buildbucket for the build status
[09:21:45.367] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:45.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:46:0) after 1m0s
[09:22:45.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:46:0)
[09:22:45.397] Timer tick, asking Buildbucket for the build status
[09:22:45.539] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:45.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:47:0) after 1m0s
[09:23:45.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:47:0)
[09:23:45.594] Timer tick, asking Buildbucket for the build status
[09:23:45.712] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:45.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:48:0) after 1m0s
[09:24:45.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:48:0)
[09:24:45.794] Timer tick, asking Buildbucket for the build status
[09:24:46.156] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:46.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:49:0) after 1m0s
[09:25:46.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:49:0)
[09:25:46.174] Timer tick, asking Buildbucket for the build status
[09:25:46.598] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:46.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:50:0) after 1m0s
[09:26:46.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:50:0)
[09:26:46.676] Timer tick, asking Buildbucket for the build status
[09:26:46.756] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:46.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:51:0) after 1m0s
[09:27:46.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:51:0)
[09:27:46.778] Timer tick, asking Buildbucket for the build status
[09:27:46.904] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:46.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:52:0) after 1m0s
[09:28:46.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:52:0)
[09:28:46.925] Timer tick, asking Buildbucket for the build status
[09:28:47.034] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:47.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:53:0) after 1m0s
[09:29:47.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:53:0)
[09:29:47.085] Timer tick, asking Buildbucket for the build status
[09:29:47.189] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:47.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:54:0) after 1m0s
[09:30:47.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:54:0)
[09:30:47.252] Timer tick, asking Buildbucket for the build status
[09:30:47.887] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:47.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:55:0) after 1m0s
[09:31:47.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:55:0)
[09:31:47.913] Timer tick, asking Buildbucket for the build status
[09:31:48.017] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:48.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:56:0) after 1m0s
[09:32:48.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:56:0)
[09:32:48.131] Timer tick, asking Buildbucket for the build status
[09:32:48.224] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:48.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:57:0) after 1m0s
[09:33:48.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:57:0)
[09:33:48.316] Timer tick, asking Buildbucket for the build status
[09:33:48.850] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:48.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:58:0) after 1m0s
[09:34:48.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:58:0)
[09:34:48.889] Timer tick, asking Buildbucket for the build status
[09:34:49.005] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:49.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:59:0) after 1m0s
[09:35:48.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:59:0)
[09:35:48.993] Timer tick, asking Buildbucket for the build status
[09:35:49.169] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:49.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:60:0) after 1m0s
[09:36:49.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:60:0)
[09:36:49.231] Timer tick, asking Buildbucket for the build status
[09:36:49.600] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:49.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:61:0) after 1m0s
[09:37:49.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:61:0)
[09:37:49.625] Timer tick, asking Buildbucket for the build status
[09:37:49.715] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:49.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:62:0) after 1m0s
[09:38:49.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:62:0)
[09:38:49.734] Timer tick, asking Buildbucket for the build status
[09:38:50.297] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:50.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:63:0) after 1m0s
[09:39:50.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:63:0)
[09:39:50.320] Timer tick, asking Buildbucket for the build status
[09:39:50.424] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:50.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:64:0) after 1m0s
[09:40:50.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:64:0)
[09:40:50.486] Timer tick, asking Buildbucket for the build status
[09:40:51.037] Build 8856059313732739376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:51.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021489375963988560:65:0) after 1m0s
[09:41:34.074] Received PubSub notification, asking Buildbucket for the build status
[09:41:34.325] Build 8856059313732739376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:41:34.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612604492993882",
"created_by": "project:chromium",
"created_ts": "1612600710130688",
"id": "8856059313732739376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"da1861760cfe68a9177503fb39d828a83b21b09d\"}}",
"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\": \"da1861760cfe68a9177503fb39d828a83b21b09d\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17197, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"0bb520adffe41d0f2007c4fe7fdf5754d2939a1d\", \"got_dawn_revision\": \"7fe5aa2eacae7ccbf0cdee379827a4567fc6845d\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"da1861760cfe68a9177503fb39d828a83b21b09d\", \"got_revision_cp\": \"refs/heads/master@{#851451}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"da1861760cfe68a9177503fb39d828a83b21b09d\", \"swarm_hashes_refs/heads/master(at){#851451}_without_patch\": {\"absl_hardening_tests\": \"2f8399d60fdc164c12d7640d5833167a68be85d7\", \"accessibility_unittests\": \"1257e64edf539eb4f370a8a9ffe1c2f8bfb299ba\", \"angle_unittests\": \"b97a5ad6c630f27bc360fdaf19892d00cfa95379\", \"app_shell_unittests\": \"f6361500eb72d71b63427ab319b610932bae9cc2\", \"aura_unittests\": \"4717624071a93b09646fcb922499dec6e25d568c\", \"base_unittests\": \"99dbc56504d70332c436ce348e1e12a3f14ef87a\", \"base_util_unittests\": \"e0fbe8139789c061712ada98ff7094b73e10a86d\", \"blink_common_unittests\": \"826c5747b5df3248f81e1cef17434c53c11c02fa\", \"blink_fuzzer_unittests\": \"20f90c618e8ece02a10a6ec63edcdd22a3fefc4d\", \"blink_heap_unittests\": \"f79dc5ab58e7fec8922b91c03293571c824ce1a1\", \"blink_platform_unittests\": \"692b2efb8196ede5cd9b5be06ab3bcce7486b416\", \"blink_unittests\": \"d150b24172667464d81e8bbc7f2a867594ba7120\", \"boringssl_crypto_tests\": \"abe1fdbd3803ac5318a41c3b61587dcc0b91a829\", \"boringssl_ssl_tests\": \"41911704fdab1339822244a682545cedbda02b57\", \"browser_switcher_bho_unittests\": \"08c334ea0f693f1f44fd7a5ba71331f0b05ecf1f\", \"browser_tests\": \"eb97e85dfc507640f812caa025beb8fd2e80372c\", \"capture_unittests\": \"8f76ab414bcae721676a31411cd224dd3c276317\", \"cast_unittests\": \"d7685a380a28e2bf1838d640b2b7129c397a1d11\", \"cc_unittests\": \"8efd4d87eb50013f9ffdc7649c8d71272aae105e\", \"chrome_app_unittests\": \"d51cfe080b3f20f54189546f27748e1db1c027ce\", \"chrome_cleaner_unittests\": \"fc6027ef7e78cef9950e735787a5a50d11249aed\", \"chrome_elf_unittests\": \"9c81fc834ca4113a59c700e0d39ad1fe31217d97\", \"chromedriver_unittests\": \"4674d01b2c075a33d1a1ce20d5bedd650f03f50b\", \"components_browsertests\": \"007360b71c728b180138ae4254c73aab5f5f894a\", \"components_unittests\": \"0a4f3a952033cfbbda4fc35b0ab7506c8b2176c2\", \"compositor_unittests\": \"69646cbf6d56a6399cb07486272eeb37a5a47325\", \"content_browsertests\": \"c24d8badf77fe2d6fc1d5073c88a4f20da8ddad0\", \"content_unittests\": \"5c184ed3b303def09f5c66ee2b3246d6b91a6aa6\", \"courgette_unittests\": \"5cbf4c11f974c2a14dff1e27e304fbd037541db9\", \"crashpad_tests\": \"1f04e0ef58248a7277e2621011e85ecffe49732e\", \"cronet_tests\": \"a6128eb94da720a5c2bdb38a6f7e4a7825a14c3f\", \"cronet_unittests\": \"d34a128dae08fee36e8c8d52659c1bcea5675846\", \"crypto_unittests\": \"0cabc2b7759f1a711040a7fbe728e53082f5100f\", \"delayloads_unittests\": \"e30b908137dc686c0c4cfe1cb6db73f61e11d1a4\", \"device_unittests\": \"d406887a35b8bea2bc42b1d6ee2b35b5a0340969\", \"display_unittests\": \"c444798ce794fe165ed7f61f3c00bafdc564c188\", \"elevation_service_unittests\": \"c35d897a2d0d9ccd6cb4d985cd87b2cfdca202cf\", \"events_unittests\": \"7c0d68295d0989aeb5d0bad838c204e9d5c5d5a6\", \"extensions_browsertests\": \"416aa1e1dc23368fb6d64f64d5bea291ea857aa6\", \"extensions_unittests\": \"d318f4d836c5b4407cfa122caa0d526154d29469\", \"filesystem_service_unittests\": \"c734b957c4a7b0583d855a52c63a243fd85efa1f\", \"gcm_unit_tests\": \"9e2f003baac0b7e6d90b70cc9af1922093ba49f9\", \"gcp_unittests\": \"bdaa3074b522c17d84d92aa13b69d53b777ea5da\", \"gfx_unittests\": \"4149394316417670a504f8c95fadde7d627c5e13\", \"gin_unittests\": \"d3222e9e3cbf85fcb1ecee854b80429620d9aa34\", \"google_apis_unittests\": \"50e7b65a2c71b3d48e0c767e4b1629d6694ab73f\", \"gpu_unittests\": \"fd41f7dff332f50a3772235afcde627513f3e11b\", \"gwp_asan_unittests\": \"1c6843dbd12f9ea743e42676a716a314bcc3f1a3\", \"headless_browsertests\": \"2878366eb30f9cf05b2987b381be63e9d2364609\", \"headless_unittests\": \"794994ccabcf0fc0556d6598d1210ac701dc47ca\", \"install_static_unittests\": \"273e174245e9efbab9032122f9138fa8a0611dc8\", \"installer_util_unittests\": \"b00c03c0be799990fec89cd4b050ee9e8efec663\", \"interactive_ui_tests\": \"9b6983b8862c032733d79af39a494aec98612f86\", \"ipc_tests\": \"f89634e4ff50f4a050f05c82bdaec58320a230bb\", \"jingle_unittests\": \"fb43677ed28e81e0cd5debadeea906f71bd0d96c\", \"latency_unittests\": \"55c7d9a192201a54210c29c84788c4b733c26121\", \"libjingle_xmpp_unittests\": \"8c4a7f34cf15f37888dc0d9deec4853b1d88fbdb\", \"liburlpattern_unittests\": \"9a69247609a880e7d03a21a30d6a9fe66f836dd3\", \"media_blink_unittests\": \"bfbd4fe2f1d21a631427aad8d2835053d740ebfb\", \"media_unittests\": \"9bc3decca9570aa945b287729a39968c741445cb\", \"message_center_unittests\": \"1765e1fc729312301fcdee7a59e3e73487ad75da\", \"midi_unittests\": \"fe6e1e1b285b4ecfa919e72b24f0b0a40146dd8c\", \"mojo_core_unittests\": \"3f35e23b9ed881628f2ffce8aedadbc5d93325dd\", \"mojo_unittests\": \"121dad749f114e83a16dfa8cbabd33734ad06b48\", \"nacl_loader_unittests\": \"f2187a0fd02bee46e4c5bed3cb070fe5ff0e1915\", \"native_theme_unittests\": \"b39a030320267ae14d7294e78848df2c87776040\", \"net_unittests\": \"0b0cfd65e69d8be7b78002fbcaa57b26f315c22a\", \"notification_helper_unittests\": \"1c48aaa51a076977387165e0f2eb5fe6577a8a76\", \"pdf_unittests\": \"7e9cf0849b693f08395808e656f2710dd7982abe\", \"perfetto_unittests\": \"6e4864f4bb490d5a8228fb8737ef493bb2a9bcc7\", \"ppapi_unittests\": \"f990594dcd220e05b86112a839310941535bd8b8\", \"printing_unittests\": \"ecfde243659da6e0532108f3a8b675bb6771f9f7\", \"remoting_unittests\": \"4673decc051aed98b3fccf85fe8c04c086678b96\", \"sbox_integration_tests\": \"c2a579e91fe7b634ee8bdfb5d5cf41651be8f2a2\", \"sbox_unittests\": \"9d6712a91892336b6b88637176803ecdba66c737\", \"sbox_validation_tests\": \"ea03ef90178524e5aef4235567d963a2e54503cd\", \"service_manager_unittests\": \"f50f7a6f7956a4ec71c6b0b73887ae3c91d9951d\", \"services_unittests\": \"294075c29035ef0763444dfdf599135d192df13e\", \"setup_unittests\": \"c7f734d9da7faba420fb8be7a1207eec3ef7c3f7\", \"shell_dialogs_unittests\": \"da17434e5998ce75fd13cd95f8ace482208c3b72\", \"skia_unittests\": \"fbeeada0bd01b78535b35aee9adb61a57751a459\", \"snapshot_unittests\": \"a58f99da4a76b0f3b1e93b3985614660cf9fac81\", \"sql_unittests\": \"e84560be6ed2f4fbb313723472ef8b7eb8e75e31\", \"storage_unittests\": \"e6d8b66f969b05feac1f7e0c7a4dd18dd8388c05\", \"sync_integration_tests\": \"95e7aa1d20454c2584ae74d466dea45d0fcff30f\", \"traffic_annotation_auditor_unittests\": \"f5e7d5512cc10e7fccb3f235c9035dd5b435bea2\", \"ui_base_unittests\": \"1d4a763c97863da4485cfba444d170220ec2932d\", \"ui_touch_selection_unittests\": \"3e903d70bce81a674e06ba8be36c11a40cf2cb19\", \"unit_tests\": \"f5e320ddbd752b95d4d6faa83a2e33b21d771707\", \"updater_tests\": \"5587118a931eebea16120d0bcd5fef7d6af304b0\", \"url_unittests\": \"ce149c257912cd28ccecca7d90cca1cde2b94129\", \"views_unittests\": \"cec0c80da67c6cf9c792a7b23c5f56d98557d7a0\", \"viz_unittests\": \"3ce87f3de420f3a49fd8ccc9ee57348486e4b579\", \"vr_common_unittests\": \"cd8fa039d22fe16c0fb56574fa112923fe5d6ed5\", \"vr_pixeltests\": \"4a82eadb90aafc30e8d58379cfd3e327c2589195\", \"weblayer_browsertests\": \"a1db6c8194e2bf50fddb652783b7e413866bcc62\", \"weblayer_unittests\": \"4bb976bd5fc91369caed176396309c3d324973c2\", \"wm_unittests\": \"d625044b340bb49c32aedffe312a6945f7c77e15\", \"wtf_unittests\": \"6a7a6e83af3e43de485484edaa124d9177fd6c85\", \"zlib_unittests\": \"d2c329ce0d677743671bda2c0943bcc8d9fcfd1f\", \"zucchini_unittests\": \"7fb8b1dc004c006cb3e82ab353f8492a5d0a09a3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612600713079001",
"status": "COMPLETED",
"status_changed_ts": "1612604493380628",
"tags": [
"build_address:luci.chromium.ci/win-asan/17197",
"builder:win-asan",
"buildset:commit/git/da1861760cfe68a9177503fb39d828a83b21b09d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da1861760cfe68a9177503fb39d828a83b21b09d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021489375963988560",
"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/8856059313732739376/+/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:518f4fa08eb6e810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612604493389545",
"url": "https://ci.chromium.org/b/8856059313732739376",
"utcnow_ts": "1612604494310802"
}
}
[09:41:34.325] Invocation finished in 1h3m5.705853327s with status SUCCEEDED