[07:28:16.823] New invocation is queued and will start shortly
[07:28:20.447] Starting the invocation (attempt 1)
[07:28:20.491] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:28:20.491] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:28:20.491] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021674986300206896",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021674986300206896",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"gitiles_ref:refs/heads/master"
]
}
[07:28:21.222] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612423700592062",
"id": "8856244921686684336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612423700931758",
"tags": [
"build_address:luci.chromium.ci/win-asan/17161",
"builder:win-asan",
"buildset:commit/git/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021674986300206896",
"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/8856244921686684336/+/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": "1612423700932054",
"url": "https://ci.chromium.org/b/8856244921686684336",
"utcnow_ts": "1612423701214839"
}
}
[07:28:21.222] Task URL: https://ci.chromium.org/b/8856244921686684336
[07:28:21.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:2:0) after 1m0s
[07:28:26.129] Received PubSub notification, asking Buildbucket for the build status
[07:28:26.273] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:21.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:2:0)
[07:29:21.242] Timer tick, asking Buildbucket for the build status
[07:29:21.346] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:21.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:4:0) after 1m0s
[07:30:21.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:4:0)
[07:30:21.369] Timer tick, asking Buildbucket for the build status
[07:30:21.925] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:21.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:5:0) after 1m0s
[07:31:21.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:5:0)
[07:31:21.936] Timer tick, asking Buildbucket for the build status
[07:31:22.110] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:22.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:6:0) after 1m0s
[07:32:22.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:6:0)
[07:32:22.104] Timer tick, asking Buildbucket for the build status
[07:32:22.630] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:22.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:7:0) after 1m0s
[07:33:22.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:7:0)
[07:33:22.610] Timer tick, asking Buildbucket for the build status
[07:33:22.715] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:8:0) after 1m0s
[07:34:22.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:8:0)
[07:34:22.727] Timer tick, asking Buildbucket for the build status
[07:34:22.784] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:22.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:9:0) after 1m0s
[07:35:22.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:9:0)
[07:35:22.801] Timer tick, asking Buildbucket for the build status
[07:35:23.228] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:23.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:10:0) after 1m0s
[07:36:23.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:10:0)
[07:36:23.248] Timer tick, asking Buildbucket for the build status
[07:36:23.597] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:23.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:11:0) after 1m0s
[07:37:23.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:11:0)
[07:37:23.623] Timer tick, asking Buildbucket for the build status
[07:37:23.744] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:23.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:12:0) after 1m0s
[07:38:23.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:12:0)
[07:38:23.726] Timer tick, asking Buildbucket for the build status
[07:38:23.836] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:23.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:13:0) after 1m0s
[07:39:23.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:13:0)
[07:39:23.818] Timer tick, asking Buildbucket for the build status
[07:39:24.952] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:24.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:14:0) after 1m0s
[07:40:24.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:14:0)
[07:40:24.999] Timer tick, asking Buildbucket for the build status
[07:40:25.103] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:25.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:15:0) after 1m0s
[07:41:25.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:15:0)
[07:41:25.124] Timer tick, asking Buildbucket for the build status
[07:41:25.197] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:25.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:16:0) after 1m0s
[07:42:25.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:16:0)
[07:42:25.322] Timer tick, asking Buildbucket for the build status
[07:42:25.532] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:25.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:17:0) after 1m0s
[07:43:25.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:17:0)
[07:43:25.533] Timer tick, asking Buildbucket for the build status
[07:43:25.831] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:25.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:18:0) after 1m0s
[07:44:25.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:18:0)
[07:44:25.858] Timer tick, asking Buildbucket for the build status
[07:44:25.923] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:25.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:19:0) after 1m0s
[07:45:25.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:19:0)
[07:45:25.982] Timer tick, asking Buildbucket for the build status
[07:45:26.112] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:20:0) after 1m0s
[07:46:26.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:20:0)
[07:46:26.217] Timer tick, asking Buildbucket for the build status
[07:46:26.407] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:26.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:21:0) after 1m0s
[07:47:26.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:21:0)
[07:47:26.412] Timer tick, asking Buildbucket for the build status
[07:47:26.557] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:26.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:22:0) after 1m0s
[07:48:26.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:22:0)
[07:48:26.525] Timer tick, asking Buildbucket for the build status
[07:48:26.853] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:26.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:23:0) after 1m0s
[07:49:26.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:23:0)
[07:49:26.958] Timer tick, asking Buildbucket for the build status
[07:49:27.108] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:27.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:24:0) after 1m0s
[07:50:27.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:24:0)
[07:50:27.203] Timer tick, asking Buildbucket for the build status
[07:50:27.298] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:27.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:25:0) after 1m0s
[07:51:27.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:25:0)
[07:51:27.437] Timer tick, asking Buildbucket for the build status
[07:51:27.612] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:27.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:26:0) after 1m0s
[07:52:27.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:26:0)
[07:52:27.723] Timer tick, asking Buildbucket for the build status
[07:52:28.125] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:28.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:27:0) after 1m0s
[07:53:28.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:27:0)
[07:53:28.331] Timer tick, asking Buildbucket for the build status
[07:53:28.808] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:28.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:28:0) after 1m0s
[07:54:28.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:28:0)
[07:54:28.843] Timer tick, asking Buildbucket for the build status
[07:54:28.935] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:28.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:29:0) after 1m0s
[07:55:28.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:29:0)
[07:55:28.995] Timer tick, asking Buildbucket for the build status
[07:55:29.106] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:29.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:30:0) after 1m0s
[07:56:29.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:30:0)
[07:56:29.276] Timer tick, asking Buildbucket for the build status
[07:56:29.410] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:29.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:31:0) after 1m0s
[07:57:29.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:31:0)
[07:57:29.436] Timer tick, asking Buildbucket for the build status
[07:57:29.558] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:29.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:32:0) after 1m0s
[07:58:29.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:32:0)
[07:58:29.603] Timer tick, asking Buildbucket for the build status
[07:58:29.760] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:29.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:33:0) after 1m0s
[07:59:29.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:33:0)
[07:59:29.797] Timer tick, asking Buildbucket for the build status
[07:59:30.338] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:30.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:34:0) after 1m0s
[08:00:30.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:34:0)
[08:00:30.383] Timer tick, asking Buildbucket for the build status
[08:00:30.507] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:30.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:35:0) after 1m0s
[08:01:30.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:35:0)
[08:01:30.536] Timer tick, asking Buildbucket for the build status
[08:01:31.355] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:31.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:36:0) after 1m0s
[08:02:31.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:36:0)
[08:02:31.426] Timer tick, asking Buildbucket for the build status
[08:02:31.489] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:31.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:37:0) after 1m0s
[08:03:31.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:37:0)
[08:03:31.529] Timer tick, asking Buildbucket for the build status
[08:03:31.634] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:31.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:38:0) after 1m0s
[08:04:31.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:38:0)
[08:04:31.646] Timer tick, asking Buildbucket for the build status
[08:04:31.785] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:31.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:39:0) after 1m0s
[08:05:31.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:39:0)
[08:05:31.874] Timer tick, asking Buildbucket for the build status
[08:05:31.945] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:31.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:40:0) after 1m0s
[08:06:31.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:40:0)
[08:06:31.949] Timer tick, asking Buildbucket for the build status
[08:06:32.022] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:32.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:41:0) after 1m0s
[08:07:32.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:41:0)
[08:07:32.066] Timer tick, asking Buildbucket for the build status
[08:07:33.106] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:33.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:42:0) after 1m0s
[08:08:33.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:42:0)
[08:08:33.151] Timer tick, asking Buildbucket for the build status
[08:08:33.351] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:33.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:43:0) after 1m0s
[08:09:33.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:43:0)
[08:09:33.454] Timer tick, asking Buildbucket for the build status
[08:09:33.709] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:33.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:44:0) after 1m0s
[08:10:33.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:44:0)
[08:10:33.769] Timer tick, asking Buildbucket for the build status
[08:10:34.314] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:34.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:45:0) after 1m0s
[08:11:34.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:45:0)
[08:11:34.369] Timer tick, asking Buildbucket for the build status
[08:11:34.564] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:34.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:46:0) after 1m0s
[08:12:34.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:46:0)
[08:12:34.557] Timer tick, asking Buildbucket for the build status
[08:12:35.010] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:35.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:47:0) after 1m0s
[08:13:35.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:47:0)
[08:13:35.077] Timer tick, asking Buildbucket for the build status
[08:13:35.220] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:35.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:48:0) after 1m0s
[08:14:35.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:48:0)
[08:14:35.235] Timer tick, asking Buildbucket for the build status
[08:14:35.525] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:35.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:49:0) after 1m0s
[08:15:35.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:49:0)
[08:15:35.515] Timer tick, asking Buildbucket for the build status
[08:15:35.654] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:35.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:50:0) after 1m0s
[08:16:35.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:50:0)
[08:16:35.732] Timer tick, asking Buildbucket for the build status
[08:16:35.944] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:35.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:51:0) after 1m0s
[08:17:36.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:51:0)
[08:17:36.006] Timer tick, asking Buildbucket for the build status
[08:17:36.324] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:36.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:52:0) after 1m0s
[08:18:36.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:52:0)
[08:18:36.400] Timer tick, asking Buildbucket for the build status
[08:18:36.678] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:36.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:53:0) after 1m0s
[08:19:36.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:53:0)
[08:19:36.656] Timer tick, asking Buildbucket for the build status
[08:19:36.756] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:36.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:54:0) after 1m0s
[08:20:36.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:54:0)
[08:20:36.793] Timer tick, asking Buildbucket for the build status
[08:20:37.307] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:37.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:55:0) after 1m0s
[08:21:37.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:55:0)
[08:21:37.374] Timer tick, asking Buildbucket for the build status
[08:21:37.445] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:37.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:56:0) after 1m0s
[08:22:37.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:56:0)
[08:22:37.467] Timer tick, asking Buildbucket for the build status
[08:22:37.530] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:37.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:57:0) after 1m0s
[08:23:37.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:57:0)
[08:23:37.533] Timer tick, asking Buildbucket for the build status
[08:23:38.031] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:38.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:58:0) after 1m0s
[08:24:38.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:58:0)
[08:24:38.061] Timer tick, asking Buildbucket for the build status
[08:24:38.124] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:38.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:59:0) after 1m0s
[08:25:38.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:59:0)
[08:25:38.179] Timer tick, asking Buildbucket for the build status
[08:25:38.509] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:38.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:60:0) after 1m0s
[08:26:38.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:60:0)
[08:26:38.579] Timer tick, asking Buildbucket for the build status
[08:26:38.647] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:38.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:61:0) after 1m0s
[08:27:38.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:61:0)
[08:27:38.753] Timer tick, asking Buildbucket for the build status
[08:27:38.891] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:38.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:62:0) after 1m0s
[08:28:38.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:62:0)
[08:28:38.915] Timer tick, asking Buildbucket for the build status
[08:28:39.105] Build 8856244921686684336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:39.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021674986300206896:63:0) after 1m0s
[08:28:58.293] Received PubSub notification, asking Buildbucket for the build status
[08:28:58.406] Build 8856244921686684336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:28:58.406] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612427337503509",
"created_by": "project:chromium",
"created_ts": "1612423700592062",
"id": "8856244921686684336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\"}}",
"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\": \"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\", \"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\": 17161, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"623d80899b7ab1ae0936387f5ab34734f3dfba97\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\", \"got_revision_cp\": \"refs/heads/master@{#850477}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"6114494c45ee29e02b0f40cc4536484ade39aa5b\", \"got_v8_revision_cp\": \"refs/heads/9.0.126@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"c5c7acecbeb1198c03cb0764a06d61a5172d7a4a\", \"swarm_hashes_refs/heads/master(at){#850477}_without_patch\": {\"absl_hardening_tests\": \"95e5bf2564a4906b7039e84e9415d1b6f46eebf6\", \"accessibility_unittests\": \"e8935a6bfcc11622972229039bd421ca15d8088d\", \"angle_unittests\": \"56066148d2654757f9685227f3f7969d65be21c5\", \"app_shell_unittests\": \"98fec8eb1ce26d1e4fa74ba3f14249ae3321321d\", \"aura_unittests\": \"37b3cabb46a441adacc887ce00225d3e8df0219f\", \"base_unittests\": \"802a2199014f7d3c7ff5d19e0897a041fff4514b\", \"base_util_unittests\": \"6b347d3ca8afc1852cf53a7b205163557a14627b\", \"blink_common_unittests\": \"8633458e74fe8455d71dc26b4e7d2cd5b9025932\", \"blink_fuzzer_unittests\": \"d4c5e147c8cea0ad3e7440a0eea203958cdabb4b\", \"blink_heap_unittests\": \"bb8e78b1100e1f4c1676b98917138ed5427f624e\", \"blink_platform_unittests\": \"dd4bb94dd6fef0a82ec5a19df0237e227b535b15\", \"blink_unittests\": \"a275c479577f0b1ddbf839dd9f0b645c13a5c707\", \"boringssl_crypto_tests\": \"9ea933882d8af52bf61bf9844e306663f5634c68\", \"boringssl_ssl_tests\": \"5f630df9ff7a0bb5e20d7b2046125b9babcbd5b8\", \"browser_switcher_bho_unittests\": \"83971e477ae90a31d681c3c82a337a93d79da935\", \"browser_tests\": \"a6c8a3e06d73ea157de85ddcc80ee3764f927bc4\", \"capture_unittests\": \"f7dafcdd7c547404b859d656a12aeed4acfb333b\", \"cast_unittests\": \"c77b6df7103f1ee7d05d2409f51d88e41bcccfd7\", \"cc_unittests\": \"a9f6f4406d6bc0fd715267f0f11c4fdd94825ed1\", \"chrome_app_unittests\": \"c5aecb60286dc0748496cf5c2eb8e33973492edb\", \"chrome_cleaner_unittests\": \"514a3e14857e8a0a3633b6444251eae10ddaeadd\", \"chrome_elf_unittests\": \"5f671c55978127f5bc562439030e8e8fad2be7f6\", \"chromedriver_unittests\": \"07192d080a112bb433e8b9dd9d2294c04ea3d0e1\", \"components_browsertests\": \"229c8ecf8d1c89a37c9def7c505fc306632095c1\", \"components_unittests\": \"1cad86e5132c63fbadeb489fc8f194934cc91903\", \"compositor_unittests\": \"2930551a59b3230a315a67f177da71fdc0b16f46\", \"content_browsertests\": \"af2583b4c97e36d2d8ca67ec889a76c7b3fbc088\", \"content_unittests\": \"48bcc3ac8eda3731b8821297a9f994de051630d5\", \"courgette_unittests\": \"6cf384d45de0b6fa81b8df5dcf5f447ac30b942d\", \"crashpad_tests\": \"3fa2c31d01984c3034f7e220df6f5b851ee63dfd\", \"cronet_tests\": \"d0daeda266334e50b38e331f87bea10d5a3f5156\", \"cronet_unittests\": \"f96cb3f96cb33225eb5fbc1776f4922ce63e0bc1\", \"crypto_unittests\": \"0b833cc1978decfd5b076f35ff24d8ce7e374135\", \"delayloads_unittests\": \"42c6d9fe778432d2fdd29cfbd3d959a728b52876\", \"device_unittests\": \"01f851114b6d382f3ca0c111e1f9d29d918418de\", \"display_unittests\": \"3e15a26a9258f3d8e3bc23fc076f515474a1675d\", \"elevation_service_unittests\": \"1de2e7cb4223e521e2bda5810afed4ad9af225ea\", \"events_unittests\": \"84f22c4523072882b22b13981c6493369ce073a6\", \"extensions_browsertests\": \"53402c5c2c66a816f72964c884b485e6fe99f021\", \"extensions_unittests\": \"cdef07f77a70281a8809a4d4dac675f3267414aa\", \"filesystem_service_unittests\": \"72582afc248505eaad3c7a4008fd393c02ce5fc3\", \"gcm_unit_tests\": \"a41ca9cbd8d418f3a3cf8034dbef5cd599506f61\", \"gcp_unittests\": \"5670eb5ec426cd4e46a7786fecb8b01992d61f76\", \"gfx_unittests\": \"14c0245baefe1bed48a11b156c9cb950c940ecfb\", \"gin_unittests\": \"4b31a69a944c685b266ebb93519e872a13ea5460\", \"google_apis_unittests\": \"7b381f3835da8c200faede7734c1bed974fa3fee\", \"gpu_unittests\": \"d6195185bdf917e3e6a4eb9a68a413f435dfdaae\", \"gwp_asan_unittests\": \"4f1d274945b15dffc53aac3dfae84c124ae6de17\", \"headless_browsertests\": \"4b0c6f8f71f46b5abb69108f8ae78281c3cf5be6\", \"headless_unittests\": \"852d7da75973158f7b36975db7065fd59b013a77\", \"install_static_unittests\": \"564c03f84d328751e25385611f2870accebc9fac\", \"installer_util_unittests\": \"418706ffbcdd11ba332c0f4a71a33f32ea92f754\", \"interactive_ui_tests\": \"6ccb1114ad63dabedc22c74b025adac134b86272\", \"ipc_tests\": \"912a0c93d7d3e13f9306c931cb9ff00e755f36da\", \"jingle_unittests\": \"8167b155b16e101d1accdf1946124ce79d341a0a\", \"latency_unittests\": \"10d6802c3d5923185adcdfdd012428b6472e7639\", \"libjingle_xmpp_unittests\": \"aa34bdb105a7e5be76bbc4d5aa082a2ea6d59199\", \"liburlpattern_unittests\": \"29a6b45f18aabac4d78355ce39cf22128291e1c1\", \"media_blink_unittests\": \"55fdf5641ac58e3016dd92633789bc66b64c7f4f\", \"media_unittests\": \"0ae58fb2f51132ad98cc121a1a91845d7296a3d2\", \"message_center_unittests\": \"8efa5eb9c41f56cc2d24eb4dff6015964526cb9e\", \"midi_unittests\": \"7b88be8733e03670fbd0213a5fb0a85bab87aaba\", \"mojo_core_unittests\": \"5f663ba9b92202272fe4a8b5e67afac5eccbeeca\", \"mojo_unittests\": \"56a503886a4a0b9d0ad698a76dc9dd48cfbda0a7\", \"nacl_loader_unittests\": \"ec81b566fd6d6482087f2e4db43810a3a013635b\", \"native_theme_unittests\": \"5c9d2ee7930daa837a43a45afd6cfccae613f6d3\", \"net_unittests\": \"52494d11090372e6c8a22cfe995f6a02dd908757\", \"notification_helper_unittests\": \"4d1ab421e21c61d69db8b583da64f3322c954150\", \"pdf_unittests\": \"22351417f136feb18307706298b13dc3e0a3f464\", \"perfetto_unittests\": \"ed05e867855a40d377c06a465c1633b2e90ae388\", \"ppapi_unittests\": \"ce37c8f182cd45489b71dab741f37e22dfb124f6\", \"printing_unittests\": \"1dabb5b0e56f6e9e5846c8d0b2ea93fc2b9152c9\", \"remoting_unittests\": \"31455a9933fb51fe2ef55ff8d3bea776c54495e9\", \"sbox_integration_tests\": \"c89f841914416a605ccd239089575f6adcbd0db0\", \"sbox_unittests\": \"054d8c2e7fcc77b8981dd583251052afa1653266\", \"sbox_validation_tests\": \"a2d44d714251a93736a90da398f9470f7d9ef530\", \"service_manager_unittests\": \"dbc11bae2ab57f5f4c29cdc2f98c6b960d944adf\", \"services_unittests\": \"3c54d3ba214024f3ef44e73375d26372755c168a\", \"setup_unittests\": \"27bcd7a93c46340e537bea55a0ef6bdca4d93e51\", \"shell_dialogs_unittests\": \"b160e7477701a434c96beeb96971577fa6410a9d\", \"skia_unittests\": \"de49eea01ed101db8ab54de29fa7ae857ca546d1\", \"snapshot_unittests\": \"58cb35b60f63c446d13df1115ba9bb57c8372725\", \"sql_unittests\": \"7a6ba238c1ef7aabf7234e417013082eceff166d\", \"storage_unittests\": \"257fb5a3a5d1a32c1166815dcdbd08fe02b541d0\", \"sync_integration_tests\": \"b5fe3166f7bc5431c9d7d02408bc2459343416a2\", \"traffic_annotation_auditor_unittests\": \"29f29f481265c5413b318494ba5f8eff1c6dc645\", \"ui_base_unittests\": \"6ad660326c1a74fd5710ca1d350547a13c097f4a\", \"ui_touch_selection_unittests\": \"0a8f40141337c3f595f2fcaa159d235ff288294e\", \"unit_tests\": \"495d934d17063a8c67ad9db53165ed0913c72df7\", \"updater_tests\": \"0f5bf5615297f0e8fca4861aeb6f24fe18f20f10\", \"url_unittests\": \"237f2e1cf37d190de3f5596038b580eef5052558\", \"views_unittests\": \"239dee55d0019774e45d060fab7a6373f1965c00\", \"viz_unittests\": \"159429f749e1b13f5a1e6720d502f94a9508451b\", \"vr_common_unittests\": \"1e53d66abe2350222247204ee3b4e33e85b04630\", \"vr_pixeltests\": \"fa4911f69429583af947ce9fc2a311d588383a09\", \"weblayer_browsertests\": \"455d096e3723e31e1002e8a71fd0b0d5980cbfdc\", \"weblayer_unittests\": \"6d31c1af2c91bab6badfbbba70c9373f64583559\", \"wm_unittests\": \"de0b321fe4bb70fd927743fb59730106f14976b3\", \"wtf_unittests\": \"5e859800546ff6b9aa1f26478385708ac4d17adb\", \"zlib_unittests\": \"230c98021daa0f5155374bd3fb0299e2eccc429a\", \"zucchini_unittests\": \"d3e4dd18bd84e7b9602c89e6b99c7619ba26f3f8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612423705386369",
"status": "COMPLETED",
"status_changed_ts": "1612427337840295",
"tags": [
"build_address:luci.chromium.ci/win-asan/17161",
"builder:win-asan",
"buildset:commit/git/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c5c7acecbeb1198c03cb0764a06d61a5172d7a4a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021674986300206896",
"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/8856244921686684336/+/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:5184c2ab7c766610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612427337845086",
"url": "https://ci.chromium.org/b/8856244921686684336",
"utcnow_ts": "1612427338394607"
}
}
[08:28:58.406] Invocation finished in 1h0m41.597981979s with status SUCCEEDED