[19:32:16.024] New invocation is queued and will start shortly
[19:32:17.131] Starting the invocation (attempt 1)
[19:32:17.170] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:32:17.170] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:32:17.170] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024981524755523824",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"12f37dd22d983cc562b9698433ee236093262aa4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024981524755523824",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/12f37dd22d983cc562b9698433ee236093262aa4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12f37dd22d983cc562b9698433ee236093262aa4",
"gitiles_ref:refs/heads/master"
]
}
[19:32:17.995] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609270337454845",
"id": "8859551462592181584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"12f37dd22d983cc562b9698433ee236093262aa4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609270337846374",
"tags": [
"build_address:luci.chromium.ci/win-asan/16535",
"builder:win-asan",
"buildset:commit/git/12f37dd22d983cc562b9698433ee236093262aa4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12f37dd22d983cc562b9698433ee236093262aa4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024981524755523824",
"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/8859551462592181584/+/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": "1609270337846488",
"url": "https://ci.chromium.org/b/8859551462592181584",
"utcnow_ts": "1609270337968752"
}
}
[19:32:17.995] Task URL: https://ci.chromium.org/b/8859551462592181584
[19:32:17.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:2:0) after 1m0s
[19:32:23.109] Received PubSub notification, asking Buildbucket for the build status
[19:32:23.299] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:18.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:2:0)
[19:33:18.051] Timer tick, asking Buildbucket for the build status
[19:33:18.164] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:18.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:4:0) after 1m0s
[19:34:18.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:4:0)
[19:34:18.246] Timer tick, asking Buildbucket for the build status
[19:34:18.399] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:18.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:5:0) after 1m0s
[19:35:18.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:5:0)
[19:35:18.434] Timer tick, asking Buildbucket for the build status
[19:35:18.591] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:18.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:6:0) after 1m0s
[19:36:18.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:6:0)
[19:36:18.681] Timer tick, asking Buildbucket for the build status
[19:36:18.850] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:18.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:7:0) after 1m0s
[19:37:19.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:7:0)
[19:37:19.096] Timer tick, asking Buildbucket for the build status
[19:37:19.209] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:19.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:8:0) after 1m0s
[19:38:19.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:8:0)
[19:38:19.236] Timer tick, asking Buildbucket for the build status
[19:38:19.399] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:19.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:9:0) after 1m0s
[19:39:19.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:9:0)
[19:39:19.429] Timer tick, asking Buildbucket for the build status
[19:39:19.555] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:19.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:10:0) after 1m0s
[19:40:19.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:10:0)
[19:40:19.686] Timer tick, asking Buildbucket for the build status
[19:40:19.784] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:19.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:11:0) after 1m0s
[19:41:19.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:11:0)
[19:41:19.881] Timer tick, asking Buildbucket for the build status
[19:41:20.000] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:20.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:12:0) after 1m0s
[19:42:20.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:12:0)
[19:42:20.232] Timer tick, asking Buildbucket for the build status
[19:42:20.554] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:20.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:13:0) after 1m0s
[19:43:20.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:13:0)
[19:43:20.660] Timer tick, asking Buildbucket for the build status
[19:43:21.012] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:21.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:14:0) after 1m0s
[19:44:21.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:14:0)
[19:44:21.060] Timer tick, asking Buildbucket for the build status
[19:44:21.202] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:21.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:15:0) after 1m0s
[19:45:21.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:15:0)
[19:45:21.616] Timer tick, asking Buildbucket for the build status
[19:45:21.723] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:21.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:16:0) after 1m0s
[19:46:21.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:16:0)
[19:46:21.837] Timer tick, asking Buildbucket for the build status
[19:46:21.892] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:21.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:17:0) after 1m0s
[19:47:21.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:17:0)
[19:47:21.967] Timer tick, asking Buildbucket for the build status
[19:47:22.497] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:22.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:18:0) after 1m0s
[19:48:22.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:18:0)
[19:48:22.582] Timer tick, asking Buildbucket for the build status
[19:48:22.735] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:22.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:19:0) after 1m0s
[19:49:22.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:19:0)
[19:49:22.908] Timer tick, asking Buildbucket for the build status
[19:49:23.038] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:23.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:20:0) after 1m0s
[19:50:23.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:20:0)
[19:50:23.246] Timer tick, asking Buildbucket for the build status
[19:50:23.373] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:23.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:21:0) after 1m0s
[19:51:23.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:21:0)
[19:51:23.415] Timer tick, asking Buildbucket for the build status
[19:51:23.668] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:23.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:22:0) after 1m0s
[19:52:23.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:22:0)
[19:52:23.680] Timer tick, asking Buildbucket for the build status
[19:52:23.836] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:23.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:23:0) after 1m0s
[19:53:23.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:23:0)
[19:53:23.848] Timer tick, asking Buildbucket for the build status
[19:53:24.001] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:24.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:24:0) after 1m0s
[19:54:24.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:24:0)
[19:54:24.046] Timer tick, asking Buildbucket for the build status
[19:54:24.465] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:24.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:25:0) after 1m0s
[19:55:24.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:25:0)
[19:55:24.509] Timer tick, asking Buildbucket for the build status
[19:55:24.637] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:24.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:26:0) after 1m0s
[19:56:24.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:26:0)
[19:56:24.660] Timer tick, asking Buildbucket for the build status
[19:56:24.867] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:24.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:27:0) after 1m0s
[19:57:24.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:27:0)
[19:57:24.914] Timer tick, asking Buildbucket for the build status
[19:57:25.072] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:25.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:28:0) after 1m0s
[19:58:25.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:28:0)
[19:58:25.094] Timer tick, asking Buildbucket for the build status
[19:58:25.270] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:25.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:29:0) after 1m0s
[19:59:25.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:29:0)
[19:59:25.262] Timer tick, asking Buildbucket for the build status
[19:59:25.862] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:25.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:30:0) after 1m0s
[20:00:25.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:30:0)
[20:00:25.894] Timer tick, asking Buildbucket for the build status
[20:00:26.140] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:26.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:31:0) after 1m0s
[20:01:26.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:31:0)
[20:01:26.162] Timer tick, asking Buildbucket for the build status
[20:01:26.310] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:32:0) after 1m0s
[20:02:26.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:32:0)
[20:02:26.371] Timer tick, asking Buildbucket for the build status
[20:02:26.756] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:26.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:33:0) after 1m0s
[20:03:26.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:33:0)
[20:03:26.820] Timer tick, asking Buildbucket for the build status
[20:03:27.039] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:27.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:34:0) after 1m0s
[20:04:27.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:34:0)
[20:04:27.111] Timer tick, asking Buildbucket for the build status
[20:04:27.331] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:27.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:35:0) after 1m0s
[20:05:27.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:35:0)
[20:05:27.377] Timer tick, asking Buildbucket for the build status
[20:05:27.525] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:36:0) after 1m0s
[20:06:27.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:36:0)
[20:06:27.525] Timer tick, asking Buildbucket for the build status
[20:06:27.714] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:27.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:37:0) after 1m0s
[20:07:27.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:37:0)
[20:07:27.788] Timer tick, asking Buildbucket for the build status
[20:07:27.947] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:38:0) after 1m0s
[20:08:28.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:38:0)
[20:08:28.048] Timer tick, asking Buildbucket for the build status
[20:08:28.196] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:28.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:39:0) after 1m0s
[20:09:28.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:39:0)
[20:09:28.301] Timer tick, asking Buildbucket for the build status
[20:09:28.707] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:28.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:40:0) after 1m0s
[20:10:28.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:40:0)
[20:10:28.796] Timer tick, asking Buildbucket for the build status
[20:10:28.929] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:28.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:41:0) after 1m0s
[20:11:28.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:41:0)
[20:11:28.975] Timer tick, asking Buildbucket for the build status
[20:11:29.089] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:29.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:42:0) after 1m0s
[20:12:29.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:42:0)
[20:12:29.137] Timer tick, asking Buildbucket for the build status
[20:12:29.533] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:29.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:43:0) after 1m0s
[20:13:29.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:43:0)
[20:13:29.555] Timer tick, asking Buildbucket for the build status
[20:13:30.009] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:30.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:44:0) after 1m0s
[20:14:30.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:44:0)
[20:14:30.032] Timer tick, asking Buildbucket for the build status
[20:14:30.870] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:30.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:45:0) after 1m0s
[20:15:30.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:45:0)
[20:15:30.902] Timer tick, asking Buildbucket for the build status
[20:15:31.127] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:46:0) after 1m0s
[20:16:31.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:46:0)
[20:16:31.144] Timer tick, asking Buildbucket for the build status
[20:16:31.371] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:31.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:47:0) after 1m0s
[20:17:31.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:47:0)
[20:17:31.382] Timer tick, asking Buildbucket for the build status
[20:17:32.033] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:32.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:48:0) after 1m0s
[20:18:32.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:48:0)
[20:18:32.032] Timer tick, asking Buildbucket for the build status
[20:18:33.426] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:33.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:49:0) after 1m0s
[20:19:33.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:49:0)
[20:19:33.537] Timer tick, asking Buildbucket for the build status
[20:19:33.665] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:33.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:50:0) after 1m0s
[20:20:33.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:50:0)
[20:20:33.695] Timer tick, asking Buildbucket for the build status
[20:20:33.878] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:33.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:51:0) after 1m0s
[20:21:33.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:51:0)
[20:21:33.973] Timer tick, asking Buildbucket for the build status
[20:21:34.126] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:52:0) after 1m0s
[20:22:34.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:52:0)
[20:22:34.199] Timer tick, asking Buildbucket for the build status
[20:22:34.562] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:34.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:53:0) after 1m0s
[20:23:34.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:53:0)
[20:23:34.712] Timer tick, asking Buildbucket for the build status
[20:23:35.048] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:35.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:54:0) after 1m0s
[20:24:35.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:54:0)
[20:24:35.152] Timer tick, asking Buildbucket for the build status
[20:24:35.309] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:35.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:55:0) after 1m0s
[20:25:35.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:55:0)
[20:25:35.404] Timer tick, asking Buildbucket for the build status
[20:25:35.523] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:35.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:56:0) after 1m0s
[20:26:35.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:56:0)
[20:26:35.538] Timer tick, asking Buildbucket for the build status
[20:26:35.650] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:35.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:57:0) after 1m0s
[20:27:35.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:57:0)
[20:27:35.718] Timer tick, asking Buildbucket for the build status
[20:27:35.906] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:35.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:58:0) after 1m0s
[20:28:36.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:58:0)
[20:28:36.010] Timer tick, asking Buildbucket for the build status
[20:28:36.143] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:36.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:59:0) after 1m0s
[20:29:36.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:59:0)
[20:29:36.346] Timer tick, asking Buildbucket for the build status
[20:29:36.465] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:36.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:60:0) after 1m0s
[20:30:36.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:60:0)
[20:30:36.572] Timer tick, asking Buildbucket for the build status
[20:30:36.880] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:36.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:61:0) after 1m0s
[20:31:36.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:61:0)
[20:31:36.916] Timer tick, asking Buildbucket for the build status
[20:31:37.572] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:37.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:62:0) after 1m0s
[20:32:37.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:62:0)
[20:32:37.662] Timer tick, asking Buildbucket for the build status
[20:32:37.869] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:37.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:63:0) after 1m0s
[20:33:37.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:63:0)
[20:33:37.902] Timer tick, asking Buildbucket for the build status
[20:33:38.288] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:38.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:64:0) after 1m0s
[20:34:38.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:64:0)
[20:34:38.318] Timer tick, asking Buildbucket for the build status
[20:34:38.448] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:38.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:65:0) after 1m0s
[20:35:38.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:65:0)
[20:35:38.472] Timer tick, asking Buildbucket for the build status
[20:35:38.637] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:38.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:66:0) after 1m0s
[20:36:38.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:66:0)
[20:36:38.670] Timer tick, asking Buildbucket for the build status
[20:36:39.106] Build 8859551462592181584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:39.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024981524755523824:67:0) after 1m0s
[20:37:15.142] Received PubSub notification, asking Buildbucket for the build status
[20:37:15.936] Build 8859551462592181584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:37:15.936] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609274232527746",
"created_by": "project:chromium",
"created_ts": "1609270337454845",
"id": "8859551462592181584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"12f37dd22d983cc562b9698433ee236093262aa4\"}}",
"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\": \"12f37dd22d983cc562b9698433ee236093262aa4\", \"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-2sgf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16535, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"095fd1d3ad25485165e395d86c40f768e0c31f4c\", \"got_dawn_revision\": \"0eb61724c8f115bc5f26bd9850f14a1dfcae4216\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"12f37dd22d983cc562b9698433ee236093262aa4\", \"got_revision_cp\": \"refs/heads/master@{#839615}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"ef6c61213b14d3cf31e97bbd314e1dda24794a0d\", \"got_v8_revision_cp\": \"refs/heads/8.9.199@{#1}\", \"got_webrtc_revision\": \"5994a16ca0769c7933881bab76e325b3b6fcad68\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32886}\", \"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\": \"12f37dd22d983cc562b9698433ee236093262aa4\", \"swarm_hashes_refs/heads/master(at){#839615}_without_patch\": {\"absl_hardening_tests\": \"0097e4dc2f6b6070785e23c92ad8ef17ca034cbf\", \"accessibility_unittests\": \"79d257d53811c69279c7b87865dc6ece69e464d8\", \"angle_unittests\": \"c4d512cfc54931a09eb88a52813bac8544fb436a\", \"app_shell_unittests\": \"547073d4ecebad03393c68775edd3ae59f4bdba8\", \"aura_unittests\": \"41005c68eb2d84b292cde673e3d6489722def7a8\", \"base_unittests\": \"3c97d5483a4a1ade5bd0a1e0e54826401588d5d9\", \"base_util_unittests\": \"d61d848ba37e95cede22dc39e4124855a285d0d7\", \"blink_common_unittests\": \"925fbc04ea8509df7d49281964d5aae33a9dbc4b\", \"blink_fuzzer_unittests\": \"56897524f336a2b1cd7eb4adfa016c6bfd4ab19a\", \"blink_heap_unittests\": \"8b1f71234d59590ab64e3220b36cc685a2c28215\", \"blink_platform_unittests\": \"bde3d5174f5cea9929a3d82a63bad6b1fa31b2f1\", \"blink_unittests\": \"d07bd79d515d4d375061c5ae362c7a8b41fc4980\", \"boringssl_crypto_tests\": \"318a52c5e9bde20554f3724c955f3164320ae2a9\", \"boringssl_ssl_tests\": \"d314473ebbf4d15a0dc08f9dd94380210a236fc7\", \"browser_switcher_bho_unittests\": \"17f47d85edecc5b8a204c119677fa2958849cda6\", \"browser_tests\": \"1fc2d95e2e75c0d21a8195fb06999cbb143b14d3\", \"capture_unittests\": \"ab0a6b9be2e05ea65d6c9e7b4a837e845cca075c\", \"cast_unittests\": \"7de5ba313d6625e7478d0e652fab415b2e313605\", \"cc_unittests\": \"2995775941a4cb7a8169274e4e01fd855673b7cf\", \"chrome_app_unittests\": \"df2ad87977a61d75bdc12533310589a399d6c0ab\", \"chrome_cleaner_unittests\": \"7cd4cbb2d7145b477122de14a02ab5c0c715ff69\", \"chrome_elf_unittests\": \"da418a4440bb6b56968d6087eb21e4f65ac0f070\", \"chromedriver_unittests\": \"3c3d403786faab533723eedc69c416aaa37c338a\", \"components_browsertests\": \"feaaeefe275bb89fb6322553b6b514fe1e85eb69\", \"components_unittests\": \"b27e6ca80d61365de3f2cd86d28137562f7fb2ee\", \"compositor_unittests\": \"66047862ddc96379492f2668b985737e40390134\", \"content_browsertests\": \"9e3a180383fcacbf13273272a5531e7736496045\", \"content_unittests\": \"077f2a824426f7a08e4fdcc4b0d9d8a014139edd\", \"courgette_unittests\": \"62b0f655b4ab8543a013e1c2e91864cb06ddcd2c\", \"crashpad_tests\": \"2c74b8e4b4ca92eaa7f4cb2b42d4e0d00d4f324b\", \"cronet_tests\": \"d8a4735af2bdd723421f9f99900ab3cf91de9cf5\", \"cronet_unittests\": \"afd4eae43f479b0b4f5766943a3560817dfc45cd\", \"crypto_unittests\": \"89d9b03092cad3b9d06e00a00c515a5d5901f83a\", \"delayloads_unittests\": \"f55ae4d4962bea7e57c6e764c8a1183346bac9fd\", \"device_unittests\": \"e4722e8a448a227c410958d3c22e04af3920d0de\", \"display_unittests\": \"d27b06623d9316747a25ad0fd04558b671367368\", \"elevation_service_unittests\": \"063fbf2850ed3ee914561440b64102a081308e65\", \"events_unittests\": \"fe5baedbdbd5b3f28656e9fcc7e6cf6b6a2c539d\", \"extensions_browsertests\": \"95f26d7003730953441970e9852e6a347e5a6010\", \"extensions_unittests\": \"be35564659df361087a20014124833499f5bd761\", \"filesystem_service_unittests\": \"4d1143f05e4b11b4b096a3a550309bee69daaf2a\", \"gcm_unit_tests\": \"d0913df0586d92db5a855d7728dacaecc630379c\", \"gcp_unittests\": \"1e4f630b3bf17dc8e6df0444dd22d384ef41b318\", \"gfx_unittests\": \"305a030e6542f6611957541f6847706c1f0c3f6b\", \"gin_unittests\": \"a913d59c42d24a4bace53ba52be01fbfd1e6c3a1\", \"google_apis_unittests\": \"763ded3fa0134bfd6c171938ef2b7bcec96156f8\", \"gpu_unittests\": \"541d73b9d7ec707430cc702a8b95071392804720\", \"gwp_asan_unittests\": \"01a69bebcf96be78bf22229cbbaa9fbf5c69cf85\", \"headless_browsertests\": \"8408a5b181a422a2a2566555dec39cbc32c2bd39\", \"headless_unittests\": \"832f76fb4fb53d2326b9e3f44577f94e86044c54\", \"install_static_unittests\": \"bbf5c252e44a9439de741d745304a9b1c29376e9\", \"installer_util_unittests\": \"979e42e83aadcb6912d460943dd64b0fa447d12e\", \"interactive_ui_tests\": \"aff27fb65ee7c17a93c3beac807b214e2c6c8da9\", \"ipc_tests\": \"da383c90bb986157eae478022e0e3b65fbcdcb8f\", \"jingle_unittests\": \"879d9c4230bc0dfdae777aa5f3d36f7c62606d1b\", \"latency_unittests\": \"786379ae669688afd2428bfd3a4f2bb2393f47c7\", \"libjingle_xmpp_unittests\": \"1d40e562328454f4a23bd0d47202f04278b19d14\", \"liburlpattern_unittests\": \"7d9dc97cef615a31d7da5b66a2af9716211628e9\", \"media_blink_unittests\": \"a43b3aacd5ed12caafd3e6231d30a16b3add3684\", \"media_unittests\": \"1bbc5675cabd4d4ea319183eaba73c654c23a1d3\", \"message_center_unittests\": \"77a7ad8f45a113d3bc5c5f57688928e995e2e027\", \"midi_unittests\": \"c0beec27042890c03fbcdaf9bf13d6340277faf4\", \"mojo_core_unittests\": \"a1a6414598f02f49ad6723523a50e858e18f6198\", \"mojo_unittests\": \"53cae490309a4704fd7775663a9871fa763bf4b3\", \"nacl_loader_unittests\": \"706ca83314a7f5b9d92ad3481f20dc0130203455\", \"native_theme_unittests\": \"3705dea7e3bfdc89505b534ecdbc1835d3bdbec9\", \"net_unittests\": \"a0a2530dcca132ab1768da06671f0fad0b08d37a\", \"notification_helper_unittests\": \"172dba2545ee858a913a248e91317282bb1e3e82\", \"pdf_unittests\": \"7342be4ef12cc186fcdd23dfde8e4c05e5e0ace9\", \"perfetto_unittests\": \"beb5acb701854af3e703e76c7d695d29592d989a\", \"ppapi_unittests\": \"d0e48b70a882c6b78125ab597b8403997d5974e7\", \"printing_unittests\": \"9f4446f80a9f98d7cfce8825b1231df48258e9b6\", \"remoting_unittests\": \"896997939fbbd3ad588f85f5b954efd33ce42104\", \"sbox_integration_tests\": \"6a7865992d0763938a7fb75d2a17f481cf2da226\", \"sbox_unittests\": \"7491769037ddd56003f21fa7981eae12d181f00f\", \"sbox_validation_tests\": \"3d3d4d4630591bcb0231cf5973c2e3920ace47c4\", \"service_manager_unittests\": \"8cc83cb212af8595899ed78acdfca57fb2314db1\", \"services_unittests\": \"2210b68bb0f1cf6fa9618951a840cc4b7b476774\", \"setup_unittests\": \"f1bdf8c3932bacaa4c56a41ea612260d27c310e4\", \"shell_dialogs_unittests\": \"af3db3e90af5ac0f3bd765e0d81deeae787bef2a\", \"skia_unittests\": \"802eac545a4fb11924da2e373d002270663f16a7\", \"snapshot_unittests\": \"a7754b12a728e280e1471cd502e2654497595ad8\", \"sql_unittests\": \"7839ffd3604fe1544e3b24ebb3c5db2bee71b332\", \"storage_unittests\": \"2505e0f437bfd750df8833727426bcca9a0425ec\", \"sync_integration_tests\": \"b3ff4782d2eddca06c2a95080a3265d33e20d22b\", \"traffic_annotation_auditor_unittests\": \"bfd6b51bc25e07a4ff5319c65b9de1d53125d455\", \"ui_base_unittests\": \"d5bcd4697bef13b4725f33e6ef01e6e16a1a8b2d\", \"ui_touch_selection_unittests\": \"4e42a9693b975ea5d448d2f0b928eaa500af763c\", \"unit_tests\": \"f5e1c4018149c63131c801e4bc78ac0eef94fb98\", \"updater_tests\": \"5100c7601aa3335ddef11154bdb76c4bc2dc8a98\", \"url_unittests\": \"3d8021a976afe9548640848e3f9e264ed8245d2c\", \"views_unittests\": \"aac4cc0ad9246bab98017cde4109e05772c814b1\", \"viz_unittests\": \"2712d27e06ee350fb9f41986cc9005c03a203d34\", \"vr_common_unittests\": \"52f22cdaec494df3a45aee61caa3a6904e9272e1\", \"vr_pixeltests\": \"624dba20ff7e93c5d11ad3994443547ce5e1e522\", \"weblayer_browsertests\": \"ee4207ab57d85ea429708b10dcac62a95528e124\", \"weblayer_unittests\": \"301a8aa239bd63e83985e8bc151c9b8f214cb245\", \"wm_unittests\": \"9f467c2f09be763878bae74598b16fcba8099e55\", \"wtf_unittests\": \"057c649152492788258651cfcab1fca0e5e2a898\", \"zlib_unittests\": \"6a6633308b12d633705f9b0f6b98710ebd6e4d32\", \"zucchini_unittests\": \"90838ce0b49085adc21246406a5316b2fa6ccdd5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-2sgf\"], \"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\": [\"5657-0417a3f\"], \"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": "1609270342513124",
"status": "COMPLETED",
"status_changed_ts": "1609274233888912",
"tags": [
"build_address:luci.chromium.ci/win-asan/16535",
"builder:win-asan",
"buildset:commit/git/12f37dd22d983cc562b9698433ee236093262aa4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12f37dd22d983cc562b9698433ee236093262aa4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024981524755523824",
"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/8859551462592181584/+/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:50c8ce2c50d96910",
"user_agent:luci-scheduler"
],
"updated_ts": "1609274233907883",
"url": "https://ci.chromium.org/b/8859551462592181584",
"utcnow_ts": "1609274235899286"
}
}
[20:37:15.936] Invocation finished in 1h4m59.929582316s with status SUCCEEDED