[18:52:21.343] New invocation is queued and will start shortly
[18:52:23.280] Starting the invocation (attempt 1)
[18:52:23.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:52:23.388] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:52:23.388] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026071199357717120",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f734f1cccfc545af804a77c0da2d82123c6a4431\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026071199357717120",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f734f1cccfc545af804a77c0da2d82123c6a4431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f734f1cccfc545af804a77c0da2d82123c6a4431",
"gitiles_ref:refs/heads/master"
]
}
[18:52:24.952] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608231143596409",
"id": "8860641136330855472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f734f1cccfc545af804a77c0da2d82123c6a4431\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608231144498920",
"tags": [
"build_address:luci.chromium.ci/win-asan/16286",
"builder:win-asan",
"buildset:commit/git/f734f1cccfc545af804a77c0da2d82123c6a4431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f734f1cccfc545af804a77c0da2d82123c6a4431",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026071199357717120",
"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/8860641136330855472/+/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": "1608231144499214",
"url": "https://ci.chromium.org/b/8860641136330855472",
"utcnow_ts": "1608231144903967"
}
}
[18:52:24.952] Task URL: https://ci.chromium.org/b/8860641136330855472
[18:52:24.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:2:0) after 1m0s
[18:52:29.109] Received PubSub notification, asking Buildbucket for the build status
[18:52:29.187] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:24.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:2:0)
[18:53:24.972] Timer tick, asking Buildbucket for the build status
[18:53:25.070] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:4:0) after 1m0s
[18:54:25.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:4:0)
[18:54:25.104] Timer tick, asking Buildbucket for the build status
[18:54:25.204] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:25.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:5:0) after 1m0s
[18:55:25.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:5:0)
[18:55:25.279] Timer tick, asking Buildbucket for the build status
[18:55:25.974] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:25.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:6:0) after 1m0s
[18:56:26.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:6:0)
[18:56:26.045] Timer tick, asking Buildbucket for the build status
[18:56:26.165] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:26.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:7:0) after 1m0s
[18:57:26.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:7:0)
[18:57:26.268] Timer tick, asking Buildbucket for the build status
[18:57:26.409] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:26.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:8:0) after 1m0s
[18:58:26.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:8:0)
[18:58:26.531] Timer tick, asking Buildbucket for the build status
[18:58:26.675] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:26.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:9:0) after 1m0s
[18:59:26.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:9:0)
[18:59:26.744] Timer tick, asking Buildbucket for the build status
[18:59:26.802] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:26.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:10:0) after 1m0s
[19:00:26.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:10:0)
[19:00:26.847] Timer tick, asking Buildbucket for the build status
[19:00:26.956] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:26.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:11:0) after 1m0s
[19:01:27.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:11:0)
[19:01:27.267] Timer tick, asking Buildbucket for the build status
[19:01:27.447] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:27.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:12:0) after 1m0s
[19:02:27.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:12:0)
[19:02:27.532] Timer tick, asking Buildbucket for the build status
[19:02:27.656] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:27.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:13:0) after 1m0s
[19:03:27.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:13:0)
[19:03:27.700] Timer tick, asking Buildbucket for the build status
[19:03:27.779] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:27.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:14:0) after 1m0s
[19:04:27.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:14:0)
[19:04:27.820] Timer tick, asking Buildbucket for the build status
[19:04:28.233] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:28.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:15:0) after 1m0s
[19:05:28.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:15:0)
[19:05:28.293] Timer tick, asking Buildbucket for the build status
[19:05:28.399] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:28.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:16:0) after 1m0s
[19:06:28.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:16:0)
[19:06:28.510] Timer tick, asking Buildbucket for the build status
[19:06:28.580] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:28.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:17:0) after 1m0s
[19:07:28.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:17:0)
[19:07:28.705] Timer tick, asking Buildbucket for the build status
[19:07:28.814] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:28.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:18:0) after 1m0s
[19:08:28.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:18:0)
[19:08:28.903] Timer tick, asking Buildbucket for the build status
[19:08:28.974] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:28.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:19:0) after 1m0s
[19:09:29.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:19:0)
[19:09:29.051] Timer tick, asking Buildbucket for the build status
[19:09:29.442] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:29.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:20:0) after 1m0s
[19:10:29.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:20:0)
[19:10:29.478] Timer tick, asking Buildbucket for the build status
[19:10:30.065] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:30.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:21:0) after 1m0s
[19:11:30.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:21:0)
[19:11:30.154] Timer tick, asking Buildbucket for the build status
[19:11:30.308] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:22:0) after 1m0s
[19:12:30.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:22:0)
[19:12:30.474] Timer tick, asking Buildbucket for the build status
[19:12:31.059] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:31.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:23:0) after 1m0s
[19:13:31.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:23:0)
[19:13:31.089] Timer tick, asking Buildbucket for the build status
[19:13:31.303] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:31.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:24:0) after 1m0s
[19:14:31.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:24:0)
[19:14:31.362] Timer tick, asking Buildbucket for the build status
[19:14:31.939] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:31.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:25:0) after 1m0s
[19:15:31.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:25:0)
[19:15:31.981] Timer tick, asking Buildbucket for the build status
[19:15:32.611] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:32.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:26:0) after 1m0s
[19:16:32.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:26:0)
[19:16:32.857] Timer tick, asking Buildbucket for the build status
[19:16:32.965] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:27:0) after 1m0s
[19:17:33.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:27:0)
[19:17:33.055] Timer tick, asking Buildbucket for the build status
[19:17:33.460] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:28:0) after 1m0s
[19:18:33.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:28:0)
[19:18:33.516] Timer tick, asking Buildbucket for the build status
[19:18:33.660] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:33.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:29:0) after 1m0s
[19:19:33.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:29:0)
[19:19:33.725] Timer tick, asking Buildbucket for the build status
[19:19:33.904] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:33.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:30:0) after 1m0s
[19:20:34.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:30:0)
[19:20:34.185] Timer tick, asking Buildbucket for the build status
[19:20:34.434] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:34.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:31:0) after 1m0s
[19:21:34.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:31:0)
[19:21:34.500] Timer tick, asking Buildbucket for the build status
[19:21:34.721] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:34.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:32:0) after 1m0s
[19:22:34.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:32:0)
[19:22:34.904] Timer tick, asking Buildbucket for the build status
[19:22:35.024] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:35.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:33:0) after 1m0s
[19:23:35.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:33:0)
[19:23:35.157] Timer tick, asking Buildbucket for the build status
[19:23:35.739] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:35.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:34:0) after 1m0s
[19:24:35.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:34:0)
[19:24:35.787] Timer tick, asking Buildbucket for the build status
[19:24:35.880] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:35.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:35:0) after 1m0s
[19:25:36.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:35:0)
[19:25:36.017] Timer tick, asking Buildbucket for the build status
[19:25:36.825] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:36:0) after 1m0s
[19:26:36.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:36:0)
[19:26:36.934] Timer tick, asking Buildbucket for the build status
[19:26:37.024] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:37.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:37:0) after 1m0s
[19:27:37.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:37:0)
[19:27:37.348] Timer tick, asking Buildbucket for the build status
[19:27:37.681] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:37.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:38:0) after 1m0s
[19:28:38.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:38:0)
[19:28:38.038] Timer tick, asking Buildbucket for the build status
[19:28:38.119] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:38.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:39:0) after 1m0s
[19:29:38.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:39:0)
[19:29:38.569] Timer tick, asking Buildbucket for the build status
[19:29:39.910] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:39.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:40:0) after 1m0s
[19:30:39.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:40:0)
[19:30:39.945] Timer tick, asking Buildbucket for the build status
[19:30:40.479] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:40.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:41:0) after 1m0s
[19:31:40.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:41:0)
[19:31:40.458] Timer tick, asking Buildbucket for the build status
[19:31:40.546] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:40.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:42:0) after 1m0s
[19:32:40.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:42:0)
[19:32:40.578] Timer tick, asking Buildbucket for the build status
[19:32:40.685] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:40.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:43:0) after 1m0s
[19:33:40.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:43:0)
[19:33:40.889] Timer tick, asking Buildbucket for the build status
[19:33:40.966] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:40.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:44:0) after 1m0s
[19:34:41.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:44:0)
[19:34:41.067] Timer tick, asking Buildbucket for the build status
[19:34:41.206] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:45:0) after 1m0s
[19:35:41.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:45:0)
[19:35:41.426] Timer tick, asking Buildbucket for the build status
[19:35:41.501] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:41.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:46:0) after 1m0s
[19:36:41.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:46:0)
[19:36:41.777] Timer tick, asking Buildbucket for the build status
[19:36:42.201] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:42.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:47:0) after 1m0s
[19:37:42.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:47:0)
[19:37:42.505] Timer tick, asking Buildbucket for the build status
[19:37:42.640] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:42.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:48:0) after 1m0s
[19:38:42.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:48:0)
[19:38:42.801] Timer tick, asking Buildbucket for the build status
[19:38:42.874] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:42.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:49:0) after 1m0s
[19:39:42.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:49:0)
[19:39:42.952] Timer tick, asking Buildbucket for the build status
[19:39:44.031] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:44.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:50:0) after 1m0s
[19:40:44.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:50:0)
[19:40:44.237] Timer tick, asking Buildbucket for the build status
[19:40:44.563] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:44.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:51:0) after 1m0s
[19:41:44.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:51:0)
[19:41:44.575] Timer tick, asking Buildbucket for the build status
[19:41:44.735] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:44.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:52:0) after 1m0s
[19:42:44.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:52:0)
[19:42:44.799] Timer tick, asking Buildbucket for the build status
[19:42:44.969] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:44.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:53:0) after 1m0s
[19:43:45.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:53:0)
[19:43:45.076] Timer tick, asking Buildbucket for the build status
[19:43:45.158] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:45.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:54:0) after 1m0s
[19:44:45.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:54:0)
[19:44:45.214] Timer tick, asking Buildbucket for the build status
[19:44:45.388] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:45.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:55:0) after 1m0s
[19:45:45.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:55:0)
[19:45:45.481] Timer tick, asking Buildbucket for the build status
[19:45:45.611] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:45.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:56:0) after 1m0s
[19:46:45.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:56:0)
[19:46:45.658] Timer tick, asking Buildbucket for the build status
[19:46:45.864] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:45.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:57:0) after 1m0s
[19:47:45.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:57:0)
[19:47:45.937] Timer tick, asking Buildbucket for the build status
[19:47:46.438] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:46.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:58:0) after 1m0s
[19:48:46.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:58:0)
[19:48:46.504] Timer tick, asking Buildbucket for the build status
[19:48:46.636] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:46.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:59:0) after 1m0s
[19:49:46.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:59:0)
[19:49:46.673] Timer tick, asking Buildbucket for the build status
[19:49:46.758] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:46.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:60:0) after 1m0s
[19:50:46.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:60:0)
[19:50:46.814] Timer tick, asking Buildbucket for the build status
[19:50:46.896] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:46.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:61:0) after 1m0s
[19:51:46.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:61:0)
[19:51:46.999] Timer tick, asking Buildbucket for the build status
[19:51:47.146] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:47.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:62:0) after 1m0s
[19:52:47.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:62:0)
[19:52:47.262] Timer tick, asking Buildbucket for the build status
[19:52:47.434] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:47.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:63:0) after 1m0s
[19:53:47.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:63:0)
[19:53:47.533] Timer tick, asking Buildbucket for the build status
[19:53:47.635] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:47.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:64:0) after 1m0s
[19:54:47.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:64:0)
[19:54:47.802] Timer tick, asking Buildbucket for the build status
[19:54:47.972] Build 8860641136330855472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:47.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026071199357717120:65:0) after 1m0s
[19:55:05.476] Received PubSub notification, asking Buildbucket for the build status
[19:55:05.585] Build 8860641136330855472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:55:05.585] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608234904540466",
"created_by": "project:chromium",
"created_ts": "1608231143596409",
"id": "8860641136330855472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f734f1cccfc545af804a77c0da2d82123c6a4431\"}}",
"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\": \"f734f1cccfc545af804a77c0da2d82123c6a4431\", \"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-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16286, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"155241b665f8305d54145ca460e0b8cebe888336\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"f734f1cccfc545af804a77c0da2d82123c6a4431\", \"got_revision_cp\": \"refs/heads/master@{#838160}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"fc4ac7024d121a1fdc77cecab42defe4bd11a967\", \"got_v8_revision_cp\": \"refs/heads/8.9.165@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"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\": \"f734f1cccfc545af804a77c0da2d82123c6a4431\", \"swarm_hashes_refs/heads/master(at){#838160}_without_patch\": {\"absl_hardening_tests\": \"062f6f85867b504cea21de11b81af01ba08637af\", \"accessibility_unittests\": \"ce0cbda005484617aa590c976c2376268b7d587d\", \"angle_unittests\": \"25c5fe344b040c6483985a31306eda44ff965d14\", \"app_shell_unittests\": \"e1ed97d40f6c59e229674dc38551d36239c8f0ea\", \"aura_unittests\": \"32ef3b6653cf2e709ca967899c1146438b53eb8c\", \"base_unittests\": \"cc1fe48cb5a72d77d41194f19d64d891ac37a101\", \"base_util_unittests\": \"208c15488e9b52e3517a063c3ccfd38c9bd10aaf\", \"blink_common_unittests\": \"15ae835399d53f28d48a213c1546130b04ab32f9\", \"blink_fuzzer_unittests\": \"1f6174ca2b5b35b2b00bf155e47dfd89d892118f\", \"blink_heap_unittests\": \"2f3c2a59ab2bd0ab5f49aeff8693a23dcd3e8a6e\", \"blink_platform_unittests\": \"692e652d2ba00d37852ada6abb4e84b8f0c6d348\", \"blink_unittests\": \"f5bdaf49d86e01e4a27e1a582df31c37975ceea1\", \"boringssl_crypto_tests\": \"3b343d2296b9d3bfefaec691b2335c2277ddc173\", \"boringssl_ssl_tests\": \"3bee9a1746b38b91d6a00bc9cedaa3bbc201af30\", \"browser_switcher_bho_unittests\": \"8588c66d2fdb75a771079b2649f6b0a297b382d3\", \"browser_tests\": \"a076f2e9a0a5bf58dff9db878bdea30b180b93ce\", \"capture_unittests\": \"cd6dec6c66cdf76a9febc072e10136b0837d4080\", \"cast_unittests\": \"063f59181154a04f59a5ac7e48766fd39e39b6fb\", \"cc_unittests\": \"270efbd4dd0b92c42817f87b4be7ae6fb8cf5193\", \"chrome_app_unittests\": \"f4fd6fe88f4ba220ae2866077d7a7501420076e4\", \"chrome_cleaner_unittests\": \"6ac96adbdd442a639c7b029038d1f9cbc3497429\", \"chrome_elf_unittests\": \"1cb07dbf5a8d92968220304e1009dd31187418e9\", \"chromedriver_unittests\": \"08611b6865e6fb56f009fd217bca6d3761624aa5\", \"components_browsertests\": \"b890602a0841b7bfb870a84a7ebf99b5e1f849da\", \"components_unittests\": \"189f5961db9331efa825e4f4bd3c1da1778e33f4\", \"compositor_unittests\": \"753bdaaddf9540d7f619b8c04a8ace47b73ca354\", \"content_browsertests\": \"a6ad9ff592597d97cb68e5779572c07dfff921c1\", \"content_unittests\": \"b56874a118fb654f309d6a94b4041bf3a8cbbc34\", \"courgette_unittests\": \"43a9c9dffb8ebe708f4a3f3462c1f2a2a7235b7e\", \"crashpad_tests\": \"01c041233de9193fd25317ddd4e7363d6d949013\", \"cronet_tests\": \"2c2248dcb7c58c9ce7c9c32dd0b78761665e076f\", \"cronet_unittests\": \"c2d864baccd13376a75364c5117837dd1902e7da\", \"crypto_unittests\": \"77d5a7ba0534f83e76d5057b57bd9127093fe231\", \"delayloads_unittests\": \"cc77dd2818563b65b911c13d0c4f80403a062569\", \"device_unittests\": \"dfd8e95624c5754d579bba4ec8b0c2fc1c08446f\", \"display_unittests\": \"7f969ed1c9c0840ff9d5c57847fc38d0233a6294\", \"elevation_service_unittests\": \"5d21af3c9585199e2d2d24fce52ecba50714f2f7\", \"events_unittests\": \"7354afce4b727c7a428dcc99edb9c82ac0e7981b\", \"extensions_browsertests\": \"acafde074287efefe30ae6fb2eb1a656fb7be813\", \"extensions_unittests\": \"c505cd13dfc4a46774d8a0afa5eb409471dee6e5\", \"filesystem_service_unittests\": \"1c9abc39f32fe9d4d64010ebe54385e6114d530d\", \"gcm_unit_tests\": \"dafa7fe430b2da4d7cc4edbee2a05249a6c708ee\", \"gcp_unittests\": \"a8c89b07d11fc751b843968ba1071cffdb91fff6\", \"gfx_unittests\": \"90d2e5042090efb2893a4b505a4d1a77cc78ea11\", \"gin_unittests\": \"1261c560e2d60ee7ad24c5be6eca9b6c8de1d9a1\", \"google_apis_unittests\": \"a8579b0137322f5a0e77d5ddfa496d69b1041e2d\", \"gpu_unittests\": \"08aea945a866d7dd21c167cfdc0d2e4b809486cb\", \"gwp_asan_unittests\": \"1a02d3368132744b28927f3c4bb2adc22061b480\", \"headless_browsertests\": \"37e3c65ecf5a7eac5a6fa7c0b7803da06ae7ee94\", \"headless_unittests\": \"a2e6ca218e68d4c5ef990e8c95f159715e821c47\", \"install_static_unittests\": \"e6e28e8df482033fe82c9cb892cac1777c8ec5c1\", \"installer_util_unittests\": \"c59f97ab4dd21d369f24cc45a3e2f5888e455e23\", \"interactive_ui_tests\": \"9fd468599d29f1916a9543f6926a2a5baf7c9d74\", \"ipc_tests\": \"1db301f5d90b138d51094a63c0c3aef9ee83a40f\", \"jingle_unittests\": \"52acf4c88f25043f50ae870a4461aa98eea9bd18\", \"latency_unittests\": \"dc022a1e353ed2612d3fbb1dea4bb91ddaffec33\", \"libjingle_xmpp_unittests\": \"87b8c54d301829936fbab9741b9ab0e861e26987\", \"liburlpattern_unittests\": \"fc7456f794097222d5166a40b5131525edd88835\", \"media_blink_unittests\": \"07cee4c13cd471d8c182db23227e1f815c1c822d\", \"media_unittests\": \"744752177f1e0bdf8dbf5a1b5ea67349af393919\", \"message_center_unittests\": \"79e693a86b607c45a21329b4df899b8c77a2b110\", \"midi_unittests\": \"9e9ae174e4df959213b80c13871fa74b5c127fd6\", \"mojo_core_unittests\": \"fffc4aa46b33e004580c9d209d4261b64fc40c34\", \"mojo_unittests\": \"dcc60f10ba980b474dbbd893881f3f8f448214c5\", \"nacl_loader_unittests\": \"1dcce809d59600914ed39cf343113d012416798d\", \"native_theme_unittests\": \"47d4dbc6fe42a69d5708e5de811990ad1090ff6f\", \"net_unittests\": \"0047bfd052821de69550a5801230472f74941f03\", \"notification_helper_unittests\": \"933a671cfa248f778eaf0e8ca8487a50bb9fcb24\", \"pdf_unittests\": \"6507871702e274adf66d509718d72c031ebfd160\", \"perfetto_unittests\": \"d55ff4f041f557a0f9763faf07ce4eb6807db4c0\", \"ppapi_unittests\": \"bb58d80f5f93ca46ea450dd0ff082e7603f67509\", \"printing_unittests\": \"e55f9dd574adf66c489b682fd2297b15a67c4094\", \"remoting_unittests\": \"19c2e926c3c11a0986169548cdba726902711219\", \"sbox_integration_tests\": \"882bb0f53dd78a2bfef71b5ba5e1a0ce0971d981\", \"sbox_unittests\": \"6db92eb8b9517511ec27f0b5d77c1c27903fec52\", \"sbox_validation_tests\": \"72b9619256a9f3a3d931d9edf160659f7395e50b\", \"service_manager_unittests\": \"29454b2ba2cb2412dc1603414c2942ce0ca4c371\", \"services_unittests\": \"b2697fe33b74f6adc39ed7243a9ec55652e84e0c\", \"setup_unittests\": \"73f6656af5c1639c53e2dc4114b960b137dcbe47\", \"shell_dialogs_unittests\": \"9f342b4371f87b970c3ebc8d868339a432030e90\", \"skia_unittests\": \"1a351a99779fd6e1cddf621dfbfa035a5efbf036\", \"snapshot_unittests\": \"42597d07515ee2a43c49b0de8f4e424d8d9e9cf4\", \"sql_unittests\": \"fed13abbb1d9403c7f2e663829008358fb324844\", \"storage_unittests\": \"5863cf8d22eb5eb4c5e93fec5d71e2c20d9c4e0f\", \"sync_integration_tests\": \"57608f52e4247f5ef130e4c2a7b0b3e060dba694\", \"traffic_annotation_auditor_unittests\": \"eba3241644f6285507a3c04d44a25649cd2d1652\", \"ui_base_unittests\": \"5cac5e7f7c9ec090747c630698091610f4ef8692\", \"ui_touch_selection_unittests\": \"a7ac2d38b579bd9646cd143c7b16de7db697fc38\", \"unit_tests\": \"e94da65c148b1d274b6de2c558db7980f69ef466\", \"updater_tests\": \"8c197452573224d519aaceccf7a75649f6978aff\", \"url_unittests\": \"6226b2cd08d6fc09f48f67952d8883b7bf2c5b94\", \"views_unittests\": \"7f0409e6b769ca15105de057951d47f9e378ba89\", \"viz_unittests\": \"0d22a03b16f7ebc443cc001899b92ab9c10c8433\", \"vr_common_unittests\": \"f9e024f947dc7a0e663eaa654801646be1b7da73\", \"vr_pixeltests\": \"290002182ec953413d9473c4a39c6a3b0eed8713\", \"weblayer_browsertests\": \"77ec8cb83173bf6526c22d2ec27941804dc82ab0\", \"weblayer_unittests\": \"e530a686cb76d38f5e06fd99b698f5bf8d8e48c6\", \"wm_unittests\": \"2b2270c2368f200f21a0570384d484178802c34e\", \"wtf_unittests\": \"4a6484fbfe3ba896d0f58d0514b7fd0da6f0a385\", \"zlib_unittests\": \"84cd296be79c3be6d7a4598c56e5841edec32501\", \"zucchini_unittests\": \"a9505d61f79c06899a5c9c606ddf2d2340c66b16\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-5-miap\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608231146319880",
"status": "COMPLETED",
"status_changed_ts": "1608234904831167",
"tags": [
"build_address:luci.chromium.ci/win-asan/16286",
"builder:win-asan",
"buildset:commit/git/f734f1cccfc545af804a77c0da2d82123c6a4431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f734f1cccfc545af804a77c0da2d82123c6a4431",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026071199357717120",
"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/8860641136330855472/+/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:508add55a964f410",
"user_agent:luci-scheduler"
],
"updated_ts": "1608234904835037",
"url": "https://ci.chromium.org/b/8860641136330855472",
"utcnow_ts": "1608234905576564"
}
}
[19:55:05.585] Invocation finished in 1h2m44.258746825s with status SUCCEEDED