[20:43:02.354] New invocation is queued and will start shortly
[20:43:03.496] Starting the invocation (attempt 1)
[20:43:03.551] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:43:03.551] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:43:03.551] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021806178339180592",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021806178339180592",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"gitiles_ref:refs/heads/master"
]
}
[20:43:04.336] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612298583640071",
"id": "8856376116319349088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612298584153562",
"tags": [
"build_address:luci.chromium.ci/win-asan/17136",
"builder:win-asan",
"buildset:commit/git/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021806178339180592",
"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/8856376116319349088/+/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": "1612298584153714",
"url": "https://ci.chromium.org/b/8856376116319349088",
"utcnow_ts": "1612298584322444"
}
}
[20:43:04.336] Task URL: https://ci.chromium.org/b/8856376116319349088
[20:43:04.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:2:0) after 1m0s
[20:43:07.912] Received PubSub notification, asking Buildbucket for the build status
[20:43:07.977] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:04.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:2:0)
[20:44:04.452] Timer tick, asking Buildbucket for the build status
[20:44:04.563] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:04.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:4:0) after 1m0s
[20:45:04.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:4:0)
[20:45:04.571] Timer tick, asking Buildbucket for the build status
[20:45:05.287] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:05.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:5:0) after 1m0s
[20:46:05.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:5:0)
[20:46:05.281] Timer tick, asking Buildbucket for the build status
[20:46:05.403] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:05.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:6:0) after 1m0s
[20:47:05.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:6:0)
[20:47:05.476] Timer tick, asking Buildbucket for the build status
[20:47:05.916] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:05.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:7:0) after 1m0s
[20:48:05.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:7:0)
[20:48:05.951] Timer tick, asking Buildbucket for the build status
[20:48:06.048] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:8:0) after 1m0s
[20:49:06.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:8:0)
[20:49:06.257] Timer tick, asking Buildbucket for the build status
[20:49:06.560] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:06.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:9:0) after 1m0s
[20:50:06.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:9:0)
[20:50:06.729] Timer tick, asking Buildbucket for the build status
[20:50:06.846] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:06.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:10:0) after 1m0s
[20:51:06.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:10:0)
[20:51:06.944] Timer tick, asking Buildbucket for the build status
[20:51:07.096] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:07.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:11:0) after 1m0s
[20:52:07.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:11:0)
[20:52:07.145] Timer tick, asking Buildbucket for the build status
[20:52:07.411] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:12:0) after 1m0s
[20:53:07.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:12:0)
[20:53:07.592] Timer tick, asking Buildbucket for the build status
[20:53:07.722] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:07.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:13:0) after 1m0s
[20:54:08.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:13:0)
[20:54:08.212] Timer tick, asking Buildbucket for the build status
[20:54:08.327] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:08.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:14:0) after 1m0s
[20:55:08.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:14:0)
[20:55:08.579] Timer tick, asking Buildbucket for the build status
[20:55:08.691] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:08.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:15:0) after 1m0s
[20:56:08.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:15:0)
[20:56:08.976] Timer tick, asking Buildbucket for the build status
[20:56:09.099] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:09.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:16:0) after 1m0s
[20:57:09.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:16:0)
[20:57:09.324] Timer tick, asking Buildbucket for the build status
[20:57:09.521] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:09.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:17:0) after 1m0s
[20:58:09.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:17:0)
[20:58:09.605] Timer tick, asking Buildbucket for the build status
[20:58:09.716] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:09.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:18:0) after 1m0s
[20:59:09.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:18:0)
[20:59:09.876] Timer tick, asking Buildbucket for the build status
[20:59:10.053] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:10.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:19:0) after 1m0s
[21:00:10.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:19:0)
[21:00:10.173] Timer tick, asking Buildbucket for the build status
[21:00:10.498] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:10.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:20:0) after 1m0s
[21:01:10.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:20:0)
[21:01:10.511] Timer tick, asking Buildbucket for the build status
[21:01:10.885] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:10.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:21:0) after 1m0s
[21:02:10.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:21:0)
[21:02:10.911] Timer tick, asking Buildbucket for the build status
[21:02:11.282] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:11.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:22:0) after 1m0s
[21:03:11.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:22:0)
[21:03:11.303] Timer tick, asking Buildbucket for the build status
[21:03:11.449] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:11.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:23:0) after 1m0s
[21:04:11.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:23:0)
[21:04:11.527] Timer tick, asking Buildbucket for the build status
[21:04:11.835] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:11.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:24:0) after 1m0s
[21:05:11.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:24:0)
[21:05:11.944] Timer tick, asking Buildbucket for the build status
[21:05:11.996] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:11.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:25:0) after 1m0s
[21:06:12.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:25:0)
[21:06:12.058] Timer tick, asking Buildbucket for the build status
[21:06:12.170] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:12.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:26:0) after 1m0s
[21:07:12.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:26:0)
[21:07:12.231] Timer tick, asking Buildbucket for the build status
[21:07:12.929] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:12.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:27:0) after 1m0s
[21:08:13.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:27:0)
[21:08:13.057] Timer tick, asking Buildbucket for the build status
[21:08:13.262] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:13.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:28:0) after 1m0s
[21:09:13.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:28:0)
[21:09:13.372] Timer tick, asking Buildbucket for the build status
[21:09:13.556] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:13.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:29:0) after 1m0s
[21:10:13.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:29:0)
[21:10:13.671] Timer tick, asking Buildbucket for the build status
[21:10:13.904] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:13.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:30:0) after 1m0s
[21:11:13.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:30:0)
[21:11:13.914] Timer tick, asking Buildbucket for the build status
[21:11:14.354] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:14.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:31:0) after 1m0s
[21:12:14.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:31:0)
[21:12:14.497] Timer tick, asking Buildbucket for the build status
[21:12:14.676] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:14.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:32:0) after 1m0s
[21:13:14.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:32:0)
[21:13:14.703] Timer tick, asking Buildbucket for the build status
[21:13:15.237] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:15.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:33:0) after 1m0s
[21:14:15.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:33:0)
[21:14:15.262] Timer tick, asking Buildbucket for the build status
[21:14:15.426] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:15.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:34:0) after 1m0s
[21:15:15.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:34:0)
[21:15:15.498] Timer tick, asking Buildbucket for the build status
[21:15:16.289] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:16.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:35:0) after 1m0s
[21:16:16.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:35:0)
[21:16:16.468] Timer tick, asking Buildbucket for the build status
[21:16:16.574] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:16.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:36:0) after 1m0s
[21:17:16.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:36:0)
[21:17:16.597] Timer tick, asking Buildbucket for the build status
[21:17:17.165] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:37:0) after 1m0s
[21:18:17.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:37:0)
[21:18:17.193] Timer tick, asking Buildbucket for the build status
[21:18:17.301] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:17.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:38:0) after 1m0s
[21:19:17.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:38:0)
[21:19:17.442] Timer tick, asking Buildbucket for the build status
[21:19:17.558] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:17.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:39:0) after 1m0s
[21:20:17.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:39:0)
[21:20:17.573] Timer tick, asking Buildbucket for the build status
[21:20:17.653] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:17.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:40:0) after 1m0s
[21:21:17.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:40:0)
[21:21:17.743] Timer tick, asking Buildbucket for the build status
[21:21:18.093] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:18.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:41:0) after 1m0s
[21:22:18.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:41:0)
[21:22:18.121] Timer tick, asking Buildbucket for the build status
[21:22:18.213] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:18.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:42:0) after 1m0s
[21:23:18.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:42:0)
[21:23:18.273] Timer tick, asking Buildbucket for the build status
[21:23:18.413] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:18.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:43:0) after 1m0s
[21:24:18.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:43:0)
[21:24:18.520] Timer tick, asking Buildbucket for the build status
[21:24:18.895] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:18.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:44:0) after 1m0s
[21:25:18.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:44:0)
[21:25:18.981] Timer tick, asking Buildbucket for the build status
[21:25:19.062] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:19.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:45:0) after 1m0s
[21:26:19.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:45:0)
[21:26:19.122] Timer tick, asking Buildbucket for the build status
[21:26:19.905] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:19.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:46:0) after 1m0s
[21:27:19.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:46:0)
[21:27:19.919] Timer tick, asking Buildbucket for the build status
[21:27:20.022] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:20.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:47:0) after 1m0s
[21:28:20.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:47:0)
[21:28:20.092] Timer tick, asking Buildbucket for the build status
[21:28:20.244] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:20.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:48:0) after 1m0s
[21:29:20.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:48:0)
[21:29:20.249] Timer tick, asking Buildbucket for the build status
[21:29:20.327] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:20.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:49:0) after 1m0s
[21:30:20.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:49:0)
[21:30:20.469] Timer tick, asking Buildbucket for the build status
[21:30:20.610] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:20.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:50:0) after 1m0s
[21:31:20.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:50:0)
[21:31:20.669] Timer tick, asking Buildbucket for the build status
[21:31:21.037] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:21.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:51:0) after 1m0s
[21:32:21.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:51:0)
[21:32:21.092] Timer tick, asking Buildbucket for the build status
[21:32:21.744] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:21.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:52:0) after 1m0s
[21:33:21.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:52:0)
[21:33:21.990] Timer tick, asking Buildbucket for the build status
[21:33:22.184] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:22.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:53:0) after 1m0s
[21:34:22.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:53:0)
[21:34:22.294] Timer tick, asking Buildbucket for the build status
[21:34:22.439] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:22.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:54:0) after 1m0s
[21:35:22.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:54:0)
[21:35:22.522] Timer tick, asking Buildbucket for the build status
[21:35:22.592] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:22.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:55:0) after 1m0s
[21:36:22.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:55:0)
[21:36:22.743] Timer tick, asking Buildbucket for the build status
[21:36:23.226] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:23.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:56:0) after 1m0s
[21:37:23.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:56:0)
[21:37:23.325] Timer tick, asking Buildbucket for the build status
[21:37:23.447] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:23.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:57:0) after 1m0s
[21:38:23.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:57:0)
[21:38:23.644] Timer tick, asking Buildbucket for the build status
[21:38:23.783] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:23.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:58:0) after 1m0s
[21:39:23.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:58:0)
[21:39:23.915] Timer tick, asking Buildbucket for the build status
[21:39:24.212] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:24.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:59:0) after 1m0s
[21:40:24.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:59:0)
[21:40:24.229] Timer tick, asking Buildbucket for the build status
[21:40:24.733] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:24.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:60:0) after 1m0s
[21:41:24.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:60:0)
[21:41:24.781] Timer tick, asking Buildbucket for the build status
[21:41:24.931] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:24.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:61:0) after 1m0s
[21:42:25.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:61:0)
[21:42:25.050] Timer tick, asking Buildbucket for the build status
[21:42:25.529] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:25.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:62:0) after 1m0s
[21:43:25.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:62:0)
[21:43:25.736] Timer tick, asking Buildbucket for the build status
[21:43:26.038] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:26.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:63:0) after 1m0s
[21:44:26.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:63:0)
[21:44:26.136] Timer tick, asking Buildbucket for the build status
[21:44:26.211] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:26.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:64:0) after 1m0s
[21:45:26.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:64:0)
[21:45:26.308] Timer tick, asking Buildbucket for the build status
[21:45:27.898] Build 8856376116319349088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:27.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021806178339180592:65:0) after 1m0s
[21:46:06.320] Received PubSub notification, asking Buildbucket for the build status
[21:46:06.408] Build 8856376116319349088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:46:06.408] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612302362373451",
"created_by": "project:chromium",
"created_ts": "1612298583640071",
"id": "8856376116319349088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\"}}",
"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\": \"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\", \"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-2-30ob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17136, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"7476121298462f0e15b3ed85acc54188c4d31859\", \"got_dawn_revision\": \"dd988bca844a6003a7249221680fc3e4f2d8b445\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\", \"got_revision_cp\": \"refs/heads/master@{#849734}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"90801c815c96dd55224b008d11b63d51400b049d\", \"got_v8_revision_cp\": \"refs/heads/9.0.115@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9\", \"swarm_hashes_refs/heads/master(at){#849734}_without_patch\": {\"absl_hardening_tests\": \"a830320ba98fb592e7570990c7c341af88fb74be\", \"accessibility_unittests\": \"4e8dbf37d1cff8e74aa8c03f457bfab3349ba940\", \"angle_unittests\": \"daae06946ea1d502789141d937b1d326e08c4bbc\", \"app_shell_unittests\": \"c4f3eadb9960dfbd19b18ef70bb1937abb994a08\", \"aura_unittests\": \"1e31c3faa9f9988fbfae0007e4acb241af2a4ebb\", \"base_unittests\": \"d9f203f0b3f4c2e42729ce9ef00b7ae1da839ae0\", \"base_util_unittests\": \"501fcd3f41ed3d03cf75e7bed6eb1572c6913e76\", \"blink_common_unittests\": \"d7cda03c2e299349e7f6237addef2e91ca7e7094\", \"blink_fuzzer_unittests\": \"ced24114805717f40afcead3d3deef6e3096393f\", \"blink_heap_unittests\": \"21f4a20767eba721bdf5a7757b9f38bdb7eb5e1e\", \"blink_platform_unittests\": \"60da0f639e933774ababf491abe1a2dfb763bd34\", \"blink_unittests\": \"2f9cdc8b61d20f94d89c369b5cd94eb89c23071f\", \"boringssl_crypto_tests\": \"aae15f1ea5f04502cf3f10709628ea8eeafc9c4c\", \"boringssl_ssl_tests\": \"729140effab0123b841459b0cc7ac1cae2780d2f\", \"browser_switcher_bho_unittests\": \"b345a10046ff520b843c559667d6fd03ec72c428\", \"browser_tests\": \"71a277a46c0c9ed2349b0e8e2c0d6a2bb9f89f90\", \"capture_unittests\": \"b89b321dafd0e712a86cbc2d0ccb64abdc7c7ac6\", \"cast_unittests\": \"579677c6352f94c77e692b8121431f032c7d6106\", \"cc_unittests\": \"658a3cef93038e956a1fff6fc9c62d850b4af67e\", \"chrome_app_unittests\": \"502d007f63b3463a70e3c5acba8f779d6746ae6d\", \"chrome_cleaner_unittests\": \"b8a5c4e9d800aa79b8f7c11ed4d4b6f410869f94\", \"chrome_elf_unittests\": \"17b61382f9504b5e7b6a45470573692b364ea8ca\", \"chromedriver_unittests\": \"885f44c2b958491805606e9221b635004a9e6139\", \"components_browsertests\": \"14dc0e57c9a9ce56f5db2dd4c2cf69dd40418563\", \"components_unittests\": \"d6accc5bdc01541b01c745201a5a7796c034b755\", \"compositor_unittests\": \"09e21370420c6cb7a991e16ed5b6ad566f325870\", \"content_browsertests\": \"0cd2cd8fec2ade92a07308ca2d6a21ba6832484c\", \"content_unittests\": \"155465ab8da1ab5ec41edb329178e6b89d294774\", \"courgette_unittests\": \"00fd4fdc79acaae7c27e6697d93380048cd4a451\", \"crashpad_tests\": \"bfed22f67a7e713bd29726d8b6faedc1bc76151f\", \"cronet_tests\": \"b7cd531fe4c533c776451086e46a504255ee25f4\", \"cronet_unittests\": \"44c857655c3078fd2557a0e0a555783275419b92\", \"crypto_unittests\": \"671976e4146c596c73a38c3df6332d2a2a519aaf\", \"delayloads_unittests\": \"7cabe8f9b1748070995027ca315b97247b6ebe8d\", \"device_unittests\": \"c1e37546882f4d3c0e5784a6a5e2282727180671\", \"display_unittests\": \"ffe98fe05b49c7bcf117a68a08746d0a0788147e\", \"elevation_service_unittests\": \"900d5bcb15b03fdee897bbb14adcd3a95b6dc4db\", \"events_unittests\": \"c735ea038ebeade7b4a7a1eed4d9d798728eec11\", \"extensions_browsertests\": \"4a9f92c5cd3196c4cd0a42c2eaf583568da649e7\", \"extensions_unittests\": \"b35f1a6a1bad918ffa4c829d3621098664e02020\", \"filesystem_service_unittests\": \"0d795a615a90bed0f33741e9671b36366c12f63c\", \"gcm_unit_tests\": \"ac2e608da47671a33bbbdaa2ff735d6f7536d1a0\", \"gcp_unittests\": \"43365b951196ce315db2cef38074a2bf3de4956b\", \"gfx_unittests\": \"722dc94997fbc75c43c6575693c95f53dc6c2183\", \"gin_unittests\": \"366884e76f3e7093f2ebd4e82046f1b96ff9c18b\", \"google_apis_unittests\": \"b204865256039e55fdc91f0b4e36b79a187a7a75\", \"gpu_unittests\": \"6dd4d860fc0d3c08928880f4f732d72ff681757b\", \"gwp_asan_unittests\": \"18dcba88a39ce7e98fe47c14c9f1ee796990ac21\", \"headless_browsertests\": \"585505d76a031e4f30d60630b257b94ae113e9ca\", \"headless_unittests\": \"e9bedf890287620530a2861d0c4f3342c9c8d6a7\", \"install_static_unittests\": \"765a4d4c1ce3c826a454652921bc6cc40773163f\", \"installer_util_unittests\": \"485e687675882227dc7906b01a96055578121b91\", \"interactive_ui_tests\": \"7a89898c902ed146b274461ee41afd15eb794624\", \"ipc_tests\": \"878c91c59aa7728efc231b96dd953f2b99790d7e\", \"jingle_unittests\": \"5a27fb6bc70904a4c94d80d6e3a3e2df13293047\", \"latency_unittests\": \"8ab44c776df610e8e7ac8f82a9b614bd8e26ec19\", \"libjingle_xmpp_unittests\": \"b86b3d5c1481eb00600d668cb2c02710baa70bdd\", \"liburlpattern_unittests\": \"e12b7b23e35757c1fa76755e78daf5809e3efb42\", \"media_blink_unittests\": \"1595023a538c88828a8963acdf8192d151d918ed\", \"media_unittests\": \"aa7d10add5957c26b0e6dbe81276df3f631132c5\", \"message_center_unittests\": \"9b4d202cfaec24eb942ddd7d13b2965a2d162f61\", \"midi_unittests\": \"4c4bc231e1b4717af01854c40678c6835aba32b5\", \"mojo_core_unittests\": \"3c3fbc4cd0ace7890badb283970da58b68045f65\", \"mojo_unittests\": \"96328f30ed5e4b5ffad00f0e638f545c46fc0a30\", \"nacl_loader_unittests\": \"cccf60b1edb6ad77ab025e74272b2c526f35c9bc\", \"native_theme_unittests\": \"be4521c6ec5c5df8fb9f0501faff74ae7f28820b\", \"net_unittests\": \"ff1e897f88e8eb8fc8300b795fc80cab38788e14\", \"notification_helper_unittests\": \"18b1b9fee6d053cc858f3f9471874740bbfd3ed3\", \"pdf_unittests\": \"46e9cc88473f9b86ba40e7fbf51c9be448454f03\", \"perfetto_unittests\": \"cc64b33996ef91cd0ab1600c0a01a7cde73a8717\", \"ppapi_unittests\": \"0ed8ce798e853541e5a3fc3db1fa2b2ecabab575\", \"printing_unittests\": \"b261ffe546dc424954eaa852875225a739fc54ff\", \"remoting_unittests\": \"4164ce42553fbe3e7f73a071bf422a20a0b5d26c\", \"sbox_integration_tests\": \"0ace7bea43243a001746baf15d63f365901afb4d\", \"sbox_unittests\": \"5312502b08e7a583d5c91e5ece27fd90ac75968f\", \"sbox_validation_tests\": \"79a709ab7c47c2a784adf71a8798075d81f6b3db\", \"service_manager_unittests\": \"e09b394a06fe4089c496167ce4e2995689648019\", \"services_unittests\": \"95f5809c82f3665a25f2b774d8469b242be182f6\", \"setup_unittests\": \"dab5433a360ec03186b9b0f82015ccfa4a0bbd83\", \"shell_dialogs_unittests\": \"ac4588a99e00dafa299d0af10e06c45753596e43\", \"skia_unittests\": \"695c548f650f0cd59394d4020c4369e6c50909a1\", \"snapshot_unittests\": \"935ddad5bc4ac713250a847c61ec811129cbd9c3\", \"sql_unittests\": \"af2ff52450ba4d501e6677922052ff971fc00a89\", \"storage_unittests\": \"f299096aa8a7e495e2991ded20049db67c66e88f\", \"sync_integration_tests\": \"e0a265a5312b56d8aff34abf10e242132d87e2dd\", \"traffic_annotation_auditor_unittests\": \"35db8642c716f0f430cbb00dd5eba564e186baad\", \"ui_base_unittests\": \"7f22a874810ff4d951de83632a136c06db5c0598\", \"ui_touch_selection_unittests\": \"65a971ae2f978a9aa33e08cf82cb6731133af5e3\", \"unit_tests\": \"a52175263dd96be8b323f04e00de81460396a7f2\", \"updater_tests\": \"b2295a957da12b9206b205c070de86691d9e615e\", \"url_unittests\": \"95fff7459e20d961edb672dced6ee91fbf17f8a3\", \"views_unittests\": \"65ce16d3ddafeff253469a78d5c3b1e2523f4c72\", \"viz_unittests\": \"038438997a189881871980dc373d3b4cf99ce69b\", \"vr_common_unittests\": \"8e2310073e7a528966cc35ded12f6f6adaa398bc\", \"vr_pixeltests\": \"4198d8fe06cec14167a7c846797d3e12f7a10885\", \"weblayer_browsertests\": \"c068e9f5b963be4740ee777809ff9864f6356e4e\", \"weblayer_unittests\": \"2a39b8f0cb4e1500d0a142806eaba8da1c474c0f\", \"wm_unittests\": \"1ce5064112d5601ce62cdb7d31df56cd97907080\", \"wtf_unittests\": \"d30c1bc12ca9bf4f505c9b8886e61c8b604c567b\", \"zlib_unittests\": \"3f73b0edecee542bc1f7c64b8ddd95be1c824d21\", \"zucchini_unittests\": \"d0a79797f9dbd3c79891ce5fd07fd2da70d6d7ab\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_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-2-30ob\"], \"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\": [\"5673-2413400\"], \"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": "1612298586898045",
"status": "COMPLETED",
"status_changed_ts": "1612302362985487",
"tags": [
"build_address:luci.chromium.ci/win-asan/17136",
"builder:win-asan",
"buildset:commit/git/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4e583c0cae170b4f9d308250d3aab3e3aa6dd9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021806178339180592",
"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/8856376116319349088/+/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:517d4d8946109010",
"user_agent:luci-scheduler"
],
"updated_ts": "1612302363139105",
"url": "https://ci.chromium.org/b/8856376116319349088",
"utcnow_ts": "1612302366387566"
}
}
[21:46:06.408] Invocation finished in 1h3m4.077807092s with status SUCCEEDED