[20:23:14.399] New invocation is queued and will start shortly
[20:23:15.599] Starting the invocation (attempt 1)
[20:23:15.658] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:23:15.658] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:23:15.658] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027877420753853968",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f33f1615e5cecd9ddf73216b635a45d5d002f430\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027877420753853968",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"gitiles_ref:refs/heads/master"
]
}
[20:23:16.285] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606508595760471",
"id": "8862447358651111120",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f33f1615e5cecd9ddf73216b635a45d5d002f430\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606508596130350",
"tags": [
"build_address:luci.chromium.ci/win-asan/15892",
"builder:win-asan",
"buildset:commit/git/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027877420753853968",
"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/8862447358651111120/+/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": "1606508596130456",
"url": "https://ci.chromium.org/b/8862447358651111120",
"utcnow_ts": "1606508596276646"
}
}
[20:23:16.285] Task URL: https://ci.chromium.org/b/8862447358651111120
[20:23:16.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:2:0) after 1m0s
[20:23:26.431] Received PubSub notification, asking Buildbucket for the build status
[20:23:26.541] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:2:0)
[20:24:16.307] Timer tick, asking Buildbucket for the build status
[20:24:16.881] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:4:0) after 1m0s
[20:25:16.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:4:0)
[20:25:16.876] Timer tick, asking Buildbucket for the build status
[20:25:17.035] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:5:0) after 1m0s
[20:26:17.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:5:0)
[20:26:17.164] Timer tick, asking Buildbucket for the build status
[20:26:17.288] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:17.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:6:0) after 1m0s
[20:27:17.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:6:0)
[20:27:17.392] Timer tick, asking Buildbucket for the build status
[20:27:17.632] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:17.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:7:0) after 1m0s
[20:28:17.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:7:0)
[20:28:17.832] Timer tick, asking Buildbucket for the build status
[20:28:17.937] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:17.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:8:0) after 1m0s
[20:29:18.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:8:0)
[20:29:18.020] Timer tick, asking Buildbucket for the build status
[20:29:18.099] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:18.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:9:0) after 1m0s
[20:30:18.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:9:0)
[20:30:18.236] Timer tick, asking Buildbucket for the build status
[20:30:18.290] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:18.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:10:0) after 1m0s
[20:31:18.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:10:0)
[20:31:18.359] Timer tick, asking Buildbucket for the build status
[20:31:18.526] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:18.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:11:0) after 1m0s
[20:32:18.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:11:0)
[20:32:18.556] Timer tick, asking Buildbucket for the build status
[20:32:18.629] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:18.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:12:0) after 1m0s
[20:33:18.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:12:0)
[20:33:18.607] Timer tick, asking Buildbucket for the build status
[20:33:18.883] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:18.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:13:0) after 1m0s
[20:34:18.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:13:0)
[20:34:18.915] Timer tick, asking Buildbucket for the build status
[20:34:19.158] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:14:0) after 1m0s
[20:35:19.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:14:0)
[20:35:19.258] Timer tick, asking Buildbucket for the build status
[20:35:19.357] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:19.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:15:0) after 1m0s
[20:36:19.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:15:0)
[20:36:19.410] Timer tick, asking Buildbucket for the build status
[20:36:20.814] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:20.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:16:0) after 1m0s
[20:37:20.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:16:0)
[20:37:20.919] Timer tick, asking Buildbucket for the build status
[20:37:21.044] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:21.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:17:0) after 1m0s
[20:38:21.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:17:0)
[20:38:21.079] Timer tick, asking Buildbucket for the build status
[20:38:21.208] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:21.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:18:0) after 1m0s
[20:39:21.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:18:0)
[20:39:21.275] Timer tick, asking Buildbucket for the build status
[20:39:21.337] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:21.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:19:0) after 1m0s
[20:40:21.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:19:0)
[20:40:21.400] Timer tick, asking Buildbucket for the build status
[20:40:21.552] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:21.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:20:0) after 1m0s
[20:41:21.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:20:0)
[20:41:21.623] Timer tick, asking Buildbucket for the build status
[20:41:21.815] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:21.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:21:0) after 1m0s
[20:42:21.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:21:0)
[20:42:21.864] Timer tick, asking Buildbucket for the build status
[20:42:22.013] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:22.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:22:0) after 1m0s
[20:43:22.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:22:0)
[20:43:22.081] Timer tick, asking Buildbucket for the build status
[20:43:22.346] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:22.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:23:0) after 1m0s
[20:44:22.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:23:0)
[20:44:22.393] Timer tick, asking Buildbucket for the build status
[20:44:22.505] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:22.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:24:0) after 1m0s
[20:45:22.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:24:0)
[20:45:22.528] Timer tick, asking Buildbucket for the build status
[20:45:22.652] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:22.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:25:0) after 1m0s
[20:46:22.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:25:0)
[20:46:22.687] Timer tick, asking Buildbucket for the build status
[20:46:22.907] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:22.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:26:0) after 1m0s
[20:47:22.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:26:0)
[20:47:22.940] Timer tick, asking Buildbucket for the build status
[20:47:23.339] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:23.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:27:0) after 1m0s
[20:48:23.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:27:0)
[20:48:23.361] Timer tick, asking Buildbucket for the build status
[20:48:23.713] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:23.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:28:0) after 1m0s
[20:49:23.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:28:0)
[20:49:23.733] Timer tick, asking Buildbucket for the build status
[20:49:23.947] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:23.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:29:0) after 1m0s
[20:50:23.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:29:0)
[20:50:23.965] Timer tick, asking Buildbucket for the build status
[20:50:24.099] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:24.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:30:0) after 1m0s
[20:51:24.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:30:0)
[20:51:24.120] Timer tick, asking Buildbucket for the build status
[20:51:24.406] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:24.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:31:0) after 1m0s
[20:52:24.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:31:0)
[20:52:24.436] Timer tick, asking Buildbucket for the build status
[20:52:24.597] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:24.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:32:0) after 1m0s
[20:53:24.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:32:0)
[20:53:24.707] Timer tick, asking Buildbucket for the build status
[20:53:25.144] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:25.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:33:0) after 1m0s
[20:54:25.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:33:0)
[20:54:25.164] Timer tick, asking Buildbucket for the build status
[20:54:25.998] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:25.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:34:0) after 1m0s
[20:55:26.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:34:0)
[20:55:26.042] Timer tick, asking Buildbucket for the build status
[20:55:26.166] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:26.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:35:0) after 1m0s
[20:56:26.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:35:0)
[20:56:26.147] Timer tick, asking Buildbucket for the build status
[20:56:26.208] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:26.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:36:0) after 1m0s
[20:57:26.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:36:0)
[20:57:26.227] Timer tick, asking Buildbucket for the build status
[20:57:26.363] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:26.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:37:0) after 1m0s
[20:58:26.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:37:0)
[20:58:26.433] Timer tick, asking Buildbucket for the build status
[20:58:26.641] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:26.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:38:0) after 1m0s
[20:59:26.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:38:0)
[20:59:26.661] Timer tick, asking Buildbucket for the build status
[20:59:26.808] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:26.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:39:0) after 1m0s
[21:00:26.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:39:0)
[21:00:26.882] Timer tick, asking Buildbucket for the build status
[21:00:27.354] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:27.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:40:0) after 1m0s
[21:01:27.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:40:0)
[21:01:27.389] Timer tick, asking Buildbucket for the build status
[21:01:27.545] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:27.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:41:0) after 1m0s
[21:02:27.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:41:0)
[21:02:27.601] Timer tick, asking Buildbucket for the build status
[21:02:28.127] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:28.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:42:0) after 1m0s
[21:03:28.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:42:0)
[21:03:28.106] Timer tick, asking Buildbucket for the build status
[21:03:28.282] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:28.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:43:0) after 1m0s
[21:04:28.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:43:0)
[21:04:28.414] Timer tick, asking Buildbucket for the build status
[21:04:28.494] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:28.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:44:0) after 1m0s
[21:05:28.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:44:0)
[21:05:28.548] Timer tick, asking Buildbucket for the build status
[21:05:28.657] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:28.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:45:0) after 1m0s
[21:06:28.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:45:0)
[21:06:28.711] Timer tick, asking Buildbucket for the build status
[21:06:29.239] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:46:0) after 1m0s
[21:07:29.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:46:0)
[21:07:29.238] Timer tick, asking Buildbucket for the build status
[21:07:29.311] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:29.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:47:0) after 1m0s
[21:08:29.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:47:0)
[21:08:29.335] Timer tick, asking Buildbucket for the build status
[21:08:30.015] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:30.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:48:0) after 1m0s
[21:09:29.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:48:0)
[21:09:29.998] Timer tick, asking Buildbucket for the build status
[21:09:30.859] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:30.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:49:0) after 1m0s
[21:10:30.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:49:0)
[21:10:30.869] Timer tick, asking Buildbucket for the build status
[21:10:30.962] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:30.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:50:0) after 1m0s
[21:11:30.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:50:0)
[21:11:30.977] Timer tick, asking Buildbucket for the build status
[21:11:31.103] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:31.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:51:0) after 1m0s
[21:12:31.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:51:0)
[21:12:31.133] Timer tick, asking Buildbucket for the build status
[21:12:31.311] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:31.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:52:0) after 1m0s
[21:13:31.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:52:0)
[21:13:31.332] Timer tick, asking Buildbucket for the build status
[21:13:31.828] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:31.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:53:0) after 1m0s
[21:14:31.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:53:0)
[21:14:31.876] Timer tick, asking Buildbucket for the build status
[21:14:32.457] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:32.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:54:0) after 1m0s
[21:15:32.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:54:0)
[21:15:32.496] Timer tick, asking Buildbucket for the build status
[21:15:32.607] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:32.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:55:0) after 1m0s
[21:16:32.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:55:0)
[21:16:32.696] Timer tick, asking Buildbucket for the build status
[21:16:32.803] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:32.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:56:0) after 1m0s
[21:17:32.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:56:0)
[21:17:32.863] Timer tick, asking Buildbucket for the build status
[21:17:32.961] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:32.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:57:0) after 1m0s
[21:18:33.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:57:0)
[21:18:33.013] Timer tick, asking Buildbucket for the build status
[21:18:33.147] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:33.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:58:0) after 1m0s
[21:19:33.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:58:0)
[21:19:33.236] Timer tick, asking Buildbucket for the build status
[21:19:33.915] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:33.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:59:0) after 1m0s
[21:20:33.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:59:0)
[21:20:33.936] Timer tick, asking Buildbucket for the build status
[21:20:34.213] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:34.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:60:0) after 1m0s
[21:21:34.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:60:0)
[21:21:34.236] Timer tick, asking Buildbucket for the build status
[21:21:34.504] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:34.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:61:0) after 1m0s
[21:22:34.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:61:0)
[21:22:34.608] Timer tick, asking Buildbucket for the build status
[21:22:34.743] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:34.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:62:0) after 1m0s
[21:23:39.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:62:0)
[21:23:39.029] Timer tick, asking Buildbucket for the build status
[21:23:39.162] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:39.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:63:0) after 1m0s
[21:24:39.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:63:0)
[21:24:39.251] Timer tick, asking Buildbucket for the build status
[21:24:39.381] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:64:0) after 1m0s
[21:25:39.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:64:0)
[21:25:39.607] Timer tick, asking Buildbucket for the build status
[21:25:39.704] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:39.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:65:0) after 1m0s
[21:26:39.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:65:0)
[21:26:39.886] Timer tick, asking Buildbucket for the build status
[21:26:40.046] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:40.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:66:0) after 1m0s
[21:27:40.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:66:0)
[21:27:40.070] Timer tick, asking Buildbucket for the build status
[21:27:40.178] Build 8862447358651111120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027877420753853968:67:0) after 1m0s
[21:28:37.281] Received PubSub notification, asking Buildbucket for the build status
[21:28:37.395] Build 8862447358651111120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:28:37.395] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606512515855278",
"created_by": "project:chromium",
"created_ts": "1606508595760471",
"id": "8862447358651111120",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f33f1615e5cecd9ddf73216b635a45d5d002f430\"}}",
"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\": \"f33f1615e5cecd9ddf73216b635a45d5d002f430\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15892, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"a5da17cd4e19649288b9634e7fff32455403b856\", \"got_dawn_revision\": \"e96986149080ca37e82788c907f439bc070aaa7b\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f33f1615e5cecd9ddf73216b635a45d5d002f430\", \"got_revision_cp\": \"refs/heads/master@{#831637}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"f33f1615e5cecd9ddf73216b635a45d5d002f430\", \"swarm_hashes_refs/heads/master(at){#831637}_without_patch\": {\"absl_hardening_tests\": \"9509b936e1e93db1fce16ea3976e7b806fa31537\", \"accessibility_unittests\": \"b453f2b5562f20323ccbb21f64ca03ad7904e667\", \"angle_unittests\": \"192deb6ef56b86df372643139292597fc48ebc25\", \"app_shell_unittests\": \"c8f0410314858828a8b794531ffe4e75f6903e56\", \"aura_unittests\": \"e1d83cd6fffa1006f9d6fc92779175968b3d0f3e\", \"base_unittests\": \"5a01b95113ab79435dbfddcab08da0c597f9d643\", \"base_util_unittests\": \"e1edb8c892de0e4d36b3c3c2f659ff90d64b953e\", \"blink_common_unittests\": \"3f243666f31317d2144bae1c6183e9f3cded23e1\", \"blink_fuzzer_unittests\": \"0373347d685fdc05f0c799b15e776108f3ac3710\", \"blink_heap_unittests\": \"f3b43f515adff94fcfbceaac5cb55509165bbbe7\", \"blink_platform_unittests\": \"82422191663023a464bf028d1934e6b38e6ccdd4\", \"blink_unittests\": \"2e56a7d68761ceb070fed29b48ef4fe92656916d\", \"boringssl_crypto_tests\": \"5ed56e4294391e789e6334bd8b4ef89d7801bb84\", \"boringssl_ssl_tests\": \"a27883cf305210b0c6c718c7620aa55d333fe9b6\", \"browser_switcher_bho_unittests\": \"aa682e26dbaf4ea03d16a9b39852f1951574bd4a\", \"browser_tests\": \"6608f3d6e0961fa6e4acd7a92170346d67269cd5\", \"capture_unittests\": \"ff18fe8ec2b88e373b8e862921ea900dc4b42466\", \"cast_unittests\": \"3edafff0126c609a52be95d46b93f8ca98bb7540\", \"cc_unittests\": \"1b7d4607894817e42f4fe4fd5d377ae05be31f89\", \"chrome_app_unittests\": \"6e6e6af1a7faef383d67c6134654aed30704314b\", \"chrome_cleaner_unittests\": \"4038fdd7fdf5a796b45735e16ab0ffe5e1cba684\", \"chrome_elf_unittests\": \"fb6bbeeea48f3758949e56b7c4ac6e610285da6e\", \"chromedriver_unittests\": \"d4c436b72df7c476cb3da99f5fcce2a61f768139\", \"components_browsertests\": \"e4e3c1c0d20b434ff064485f03e4c26aa3808296\", \"components_unittests\": \"2b99ef4e5121d3e3e4a09c7ccbc7c31e0e74f4c3\", \"compositor_unittests\": \"6c3aa6c288b21af6ec91d6bab9c20e8f6dc23bf6\", \"content_browsertests\": \"313ef48f7808c4d0d9b80bb7d1d464a6d861efdc\", \"content_unittests\": \"0ece6d9f1d96a80f11fba17bc3c8a1d0982499b7\", \"courgette_unittests\": \"da8dcd05044e7e94cf2f9c858ac5f00f0abd6e74\", \"crashpad_tests\": \"4231be2777e5527e07ac488ad2db6c4b31d13bb9\", \"cronet_tests\": \"94bd7b923903690737a258fef66184cd75fe37e0\", \"cronet_unittests\": \"7751056232f00b913c7a8c6540291d4b685d5ea2\", \"crypto_unittests\": \"9e3e9ea2685b65b9eb88cbd73754461955da9d30\", \"delayloads_unittests\": \"98cf409ac4aa78283c37a66aadc74021aa9d0782\", \"device_unittests\": \"5332650fa98fefe685aa4dd2bce726b6499857ed\", \"display_unittests\": \"51799191667b3f70c8995748c3d6b5d276baf61e\", \"elevation_service_unittests\": \"f7fb4a8296f8696c95a2fb7896142e7adba3cc5e\", \"events_unittests\": \"2bf55fa9dc4a09b49080ce05b1107a7e145c477f\", \"extensions_browsertests\": \"fce68d7ffb04a36ab66c557f142baf03fb80a26a\", \"extensions_unittests\": \"afeb0816368f0d265ca6ba3ec06626e4603313d5\", \"filesystem_service_unittests\": \"c74aabc5cf8b91ea1c7c50b05e3a3822db0fb0db\", \"gcm_unit_tests\": \"e2a8bdc8cb3c1a0425be5abff5a4138b025bb0cc\", \"gcp_unittests\": \"7aefc6b9f2652c6f7bd3ae4e37f8e0900a08ca9e\", \"gfx_unittests\": \"259b609ee8adc78ca5162f777c76882fa0e5312e\", \"gin_unittests\": \"49c893b177577058993259734c659e4e8e27065a\", \"google_apis_unittests\": \"ad9690cc9dd6801c3a779172a1ec8227155e7ce8\", \"gpu_unittests\": \"cedfbfcc3a7d58e978d7cf055c9366de70c33309\", \"gwp_asan_unittests\": \"8e1650ed78e7489fc10981a41cfb88d6d0553c0d\", \"headless_browsertests\": \"cdead7953fa21662b0d51f8e55666087035af0dd\", \"headless_unittests\": \"d9bf2f9e21b4ca78834efeeaad1877d495bacead\", \"install_static_unittests\": \"2cea63a14149a580deea62da5e6497b168fe1c55\", \"installer_util_unittests\": \"678ab13a664768f51e547a9105fb65df401db970\", \"interactive_ui_tests\": \"32d17f29de9b358645d7a6605c987cbf3fc880c3\", \"ipc_tests\": \"e1c9c3303b094c4f50d67d7abed987a2fbccba1f\", \"jingle_unittests\": \"03c81238c77f4abeec7e2954f7c944333c97aedd\", \"latency_unittests\": \"7e504834a8562471a893fef37e57cb8827e0a032\", \"libjingle_xmpp_unittests\": \"e94ecbfca99448bdb459f44e8cd7d438a5898f71\", \"liburlpattern_unittests\": \"8becd94e286821bb962ffb78b9bd9710d57e7d26\", \"media_blink_unittests\": \"aa5f8c6408e54b76d4ab718a142f635a18e39b35\", \"media_unittests\": \"1ded741caead17dc4b143228af2ebd7d9db9c4a2\", \"message_center_unittests\": \"92371a47df24d8293b8d2cad835895aab3881a8f\", \"midi_unittests\": \"236571f082a386b17596329707f3a612b61df1cc\", \"mojo_core_unittests\": \"eab4948b2421d413e680f349c9c1c29333dfae06\", \"mojo_unittests\": \"de3f923e55eb2703b769edac40733b31cd778089\", \"nacl_loader_unittests\": \"dee495f5c8fe17b7649e9b1c1fc6583b0e2c3833\", \"native_theme_unittests\": \"c15699a79ef0ba0b00a682f0680cc58b6817bcde\", \"net_unittests\": \"bf7972a8141477417ab490df8dd2931a7725096a\", \"notification_helper_unittests\": \"d90e24b5cec87eeb621351b6a5bb24a3fd9834f6\", \"pdf_unittests\": \"10cbbbcecb97b8fdb819a18c0bc616377ad6fc9d\", \"perfetto_unittests\": \"70f78ad0db5bbbaf7d9f463b30801e25143462e8\", \"ppapi_unittests\": \"fa9c176fdef7aa2ea774aae4ee6fc614831b19d2\", \"printing_unittests\": \"3d6fb7fe7dc946399fa85014a5c9ba55cab4ddb9\", \"remoting_unittests\": \"16bdb49ca4d25518a842f9b97654e59a9417b7fd\", \"sbox_integration_tests\": \"68c581528953aeda37be48e488fee96c586ecbed\", \"sbox_unittests\": \"22c289e956baad152bdc70ab7664ab33c693f45f\", \"sbox_validation_tests\": \"b416829327ac687f70ff60f4b1a1611db6cf232e\", \"service_manager_unittests\": \"30cac1a28644fdff5485f1e6f77f2c5c276e8dd1\", \"services_unittests\": \"1bcce4c79c6bfa11a79685080abf0aac07a8680c\", \"setup_unittests\": \"5db0fe135a20e8cbbfa58fb531347656f23d7228\", \"shell_dialogs_unittests\": \"da89b286d4550b17f6a056858097c414acec2752\", \"skia_unittests\": \"4bfc18378d4bd39252396546b10bcd686b72814e\", \"snapshot_unittests\": \"938ff2840f883f8c01346e8e16d96b1dba57b44b\", \"sql_unittests\": \"7b0b80233e090efbfd19e40755d8efd562140154\", \"storage_unittests\": \"ca62929795ec1596add6db9035353a7ccbc4d8b8\", \"sync_integration_tests\": \"d83ba9d4f68391e8d25a91b8929af075f709f75b\", \"traffic_annotation_auditor_unittests\": \"fa69f52c8f9031dd5259f2a39072e8cede12eb1d\", \"ui_base_unittests\": \"d81231ae7f445af41db1fec55131f5e6bd395dd3\", \"ui_touch_selection_unittests\": \"79e1060850f454babe3722931f51a8e0ca0fc071\", \"unit_tests\": \"bdec6f34b8015e7b21d42a60cbd47c722e6b82f9\", \"updater_tests\": \"e09dc1406c91fa51250b1df85b29aa16dc64eec3\", \"url_unittests\": \"4d2f28e5f33e832ba9417998319ca698f5fc03d9\", \"views_unittests\": \"6e56fbda87cc6f6a1197f4eab111e6db5296522e\", \"viz_unittests\": \"5c615382707c8b4bf3e46cd5147069317b4ad803\", \"vr_common_unittests\": \"fa477d3ce6a4bd859fb9d8a0a795b38265ad8311\", \"vr_pixeltests\": \"2f591b40c78db1383b8890ba0d9834696694c7f6\", \"weblayer_browsertests\": \"7b37876b9f635532cb9940f131fde186215ce82f\", \"weblayer_unittests\": \"c3971d0b769b7a4c256ebc0e165b557e607cdffe\", \"wm_unittests\": \"c46904856d7daede6fb99a266dff6cc05db9195d\", \"wtf_unittests\": \"f8e2a1ad1e18003c7c510b6dd147d139d2f2034d\", \"zlib_unittests\": \"074656959fac523a08e63074e3c144b21b39257d\", \"zucchini_unittests\": \"fd55c3644f4239fdc58c5ad73a1ee0f9fa7b528f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_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-4-qhsy\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606508605009547",
"status": "COMPLETED",
"status_changed_ts": "1606512516949853",
"tags": [
"build_address:luci.chromium.ci/win-asan/15892",
"builder:win-asan",
"buildset:commit/git/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f33f1615e5cecd9ddf73216b635a45d5d002f430",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027877420753853968",
"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/8862447358651111120/+/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:50243154fd1f3310",
"user_agent:luci-scheduler"
],
"updated_ts": "1606512516959341",
"url": "https://ci.chromium.org/b/8862447358651111120",
"utcnow_ts": "1606512517384651"
}
}
[21:28:37.395] Invocation finished in 1h5m23.024339582s with status SUCCEEDED