[16:10:18.121] New invocation is queued and will start shortly
[16:10:19.205] Starting the invocation (attempt 1)
[16:10:19.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:10:19.283] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:10:19.283] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028799303888264512",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028799303888264512",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"gitiles_ref:refs/heads/master"
]
}
[16:10:20.114] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605629419409363",
"id": "8863369241872030816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605629419934776",
"tags": [
"build_address:luci.chromium.ci/win-asan/15690",
"builder:win-asan",
"buildset:commit/git/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028799303888264512",
"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/8863369241872030816/+/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": "1605629419934959",
"url": "https://ci.chromium.org/b/8863369241872030816",
"utcnow_ts": "1605629420102947"
}
}
[16:10:20.114] Task URL: https://ci.chromium.org/b/8863369241872030816
[16:10:20.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:2:0) after 1m0s
[16:10:31.427] Received PubSub notification, asking Buildbucket for the build status
[16:10:31.516] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:20.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:2:0)
[16:11:20.155] Timer tick, asking Buildbucket for the build status
[16:11:20.239] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:4:0) after 1m0s
[16:12:20.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:4:0)
[16:12:20.378] Timer tick, asking Buildbucket for the build status
[16:12:20.702] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:20.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:5:0) after 1m0s
[16:13:20.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:5:0)
[16:13:20.940] Timer tick, asking Buildbucket for the build status
[16:13:21.081] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:21.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:6:0) after 1m0s
[16:14:21.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:6:0)
[16:14:21.386] Timer tick, asking Buildbucket for the build status
[16:14:21.534] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:21.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:7:0) after 1m0s
[16:15:21.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:7:0)
[16:15:21.804] Timer tick, asking Buildbucket for the build status
[16:15:22.123] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:22.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:8:0) after 1m0s
[16:16:22.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:8:0)
[16:16:22.177] Timer tick, asking Buildbucket for the build status
[16:16:22.304] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:9:0) after 1m0s
[16:17:22.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:9:0)
[16:17:22.392] Timer tick, asking Buildbucket for the build status
[16:17:22.539] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:22.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:10:0) after 1m0s
[16:18:22.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:10:0)
[16:18:22.717] Timer tick, asking Buildbucket for the build status
[16:18:22.782] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:22.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:11:0) after 1m0s
[16:19:22.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:11:0)
[16:19:22.827] Timer tick, asking Buildbucket for the build status
[16:19:22.998] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:22.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:12:0) after 1m0s
[16:20:23.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:12:0)
[16:20:23.058] Timer tick, asking Buildbucket for the build status
[16:20:23.133] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:23.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:13:0) after 1m0s
[16:21:23.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:13:0)
[16:21:23.358] Timer tick, asking Buildbucket for the build status
[16:21:23.506] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:23.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:14:0) after 1m0s
[16:22:23.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:14:0)
[16:22:23.549] Timer tick, asking Buildbucket for the build status
[16:22:23.624] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:23.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:15:0) after 1m0s
[16:23:23.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:15:0)
[16:23:23.752] Timer tick, asking Buildbucket for the build status
[16:23:26.207] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:26.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:16:0) after 1m0s
[16:24:26.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:16:0)
[16:24:26.248] Timer tick, asking Buildbucket for the build status
[16:24:26.416] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:26.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:17:0) after 1m0s
[16:25:26.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:17:0)
[16:25:26.513] Timer tick, asking Buildbucket for the build status
[16:25:26.587] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:26.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:18:0) after 1m0s
[16:26:26.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:18:0)
[16:26:26.652] Timer tick, asking Buildbucket for the build status
[16:26:26.793] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:26.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:19:0) after 1m0s
[16:27:26.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:19:0)
[16:27:26.851] Timer tick, asking Buildbucket for the build status
[16:27:27.105] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:27.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:20:0) after 1m0s
[16:28:27.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:20:0)
[16:28:27.157] Timer tick, asking Buildbucket for the build status
[16:28:27.249] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:27.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:21:0) after 1m0s
[16:29:27.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:21:0)
[16:29:27.257] Timer tick, asking Buildbucket for the build status
[16:29:27.423] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:27.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:22:0) after 1m0s
[16:30:27.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:22:0)
[16:30:27.508] Timer tick, asking Buildbucket for the build status
[16:30:27.808] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:27.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:23:0) after 1m0s
[16:31:27.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:23:0)
[16:31:27.902] Timer tick, asking Buildbucket for the build status
[16:31:28.018] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:28.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:24:0) after 1m0s
[16:32:28.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:24:0)
[16:32:28.157] Timer tick, asking Buildbucket for the build status
[16:32:28.704] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:25:0) after 1m0s
[16:33:28.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:25:0)
[16:33:28.780] Timer tick, asking Buildbucket for the build status
[16:33:29.298] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:29.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:26:0) after 1m0s
[16:34:29.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:26:0)
[16:34:29.331] Timer tick, asking Buildbucket for the build status
[16:34:29.531] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:29.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:27:0) after 1m0s
[16:35:29.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:27:0)
[16:35:29.615] Timer tick, asking Buildbucket for the build status
[16:35:29.782] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:29.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:28:0) after 1m0s
[16:36:29.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:28:0)
[16:36:29.845] Timer tick, asking Buildbucket for the build status
[16:36:29.965] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:29.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:29:0) after 1m0s
[16:37:30.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:29:0)
[16:37:30.033] Timer tick, asking Buildbucket for the build status
[16:37:30.236] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:30.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:30:0) after 1m0s
[16:38:30.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:30:0)
[16:38:30.334] Timer tick, asking Buildbucket for the build status
[16:38:30.460] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:30.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:31:0) after 1m0s
[16:39:30.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:31:0)
[16:39:30.482] Timer tick, asking Buildbucket for the build status
[16:39:30.629] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:30.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:32:0) after 1m0s
[16:40:30.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:32:0)
[16:40:30.767] Timer tick, asking Buildbucket for the build status
[16:40:30.902] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:30.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:33:0) after 1m0s
[16:41:32.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:33:0)
[16:41:32.156] Timer tick, asking Buildbucket for the build status
[16:41:32.285] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:32.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:34:0) after 1m0s
[16:42:32.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:34:0)
[16:42:32.649] Timer tick, asking Buildbucket for the build status
[16:42:33.157] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:33.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:35:0) after 1m0s
[16:43:33.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:35:0)
[16:43:33.379] Timer tick, asking Buildbucket for the build status
[16:43:33.494] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:33.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:36:0) after 1m0s
[16:44:33.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:36:0)
[16:44:33.665] Timer tick, asking Buildbucket for the build status
[16:44:34.058] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:34.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:37:0) after 1m0s
[16:45:34.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:37:0)
[16:45:34.324] Timer tick, asking Buildbucket for the build status
[16:45:34.444] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:34.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:38:0) after 1m0s
[16:46:34.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:38:0)
[16:46:34.618] Timer tick, asking Buildbucket for the build status
[16:46:36.296] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:36.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:39:0) after 1m0s
[16:47:37.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:39:0)
[16:47:37.926] Timer tick, asking Buildbucket for the build status
[16:47:39.060] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:39.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:40:0) after 1m0s
[16:48:39.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:40:0)
[16:48:39.113] Timer tick, asking Buildbucket for the build status
[16:48:39.252] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:39.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:41:0) after 1m0s
[16:49:39.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:41:0)
[16:49:39.288] Timer tick, asking Buildbucket for the build status
[16:49:39.476] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:39.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:42:0) after 1m0s
[16:50:39.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:42:0)
[16:50:39.548] Timer tick, asking Buildbucket for the build status
[16:50:39.716] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:39.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:43:0) after 1m0s
[16:51:39.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:43:0)
[16:51:39.763] Timer tick, asking Buildbucket for the build status
[16:51:39.891] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:39.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:44:0) after 1m0s
[16:52:39.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:44:0)
[16:52:39.994] Timer tick, asking Buildbucket for the build status
[16:52:40.167] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:40.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:45:0) after 1m0s
[16:53:40.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:45:0)
[16:53:40.240] Timer tick, asking Buildbucket for the build status
[16:53:40.370] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:40.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:46:0) after 1m0s
[16:54:40.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:46:0)
[16:54:40.423] Timer tick, asking Buildbucket for the build status
[16:54:40.555] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:40.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:47:0) after 1m0s
[16:55:40.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:47:0)
[16:55:40.634] Timer tick, asking Buildbucket for the build status
[16:55:40.759] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:40.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:48:0) after 1m0s
[16:56:40.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:48:0)
[16:56:40.857] Timer tick, asking Buildbucket for the build status
[16:56:40.933] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:40.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:49:0) after 1m0s
[16:57:40.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:49:0)
[16:57:40.937] Timer tick, asking Buildbucket for the build status
[16:57:41.031] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:41.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:50:0) after 1m0s
[16:58:41.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:50:0)
[16:58:41.114] Timer tick, asking Buildbucket for the build status
[16:58:41.198] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:41.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:51:0) after 1m0s
[16:59:41.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:51:0)
[16:59:41.234] Timer tick, asking Buildbucket for the build status
[16:59:41.376] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:41.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:52:0) after 1m0s
[17:00:41.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:52:0)
[17:00:41.403] Timer tick, asking Buildbucket for the build status
[17:00:41.624] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:41.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:53:0) after 1m0s
[17:01:41.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:53:0)
[17:01:41.683] Timer tick, asking Buildbucket for the build status
[17:01:41.811] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:41.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:54:0) after 1m0s
[17:02:41.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:54:0)
[17:02:41.972] Timer tick, asking Buildbucket for the build status
[17:02:42.103] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:42.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:55:0) after 1m0s
[17:03:42.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:55:0)
[17:03:42.239] Timer tick, asking Buildbucket for the build status
[17:03:42.453] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:42.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:56:0) after 1m0s
[17:04:42.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:56:0)
[17:04:42.528] Timer tick, asking Buildbucket for the build status
[17:04:43.530] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:43.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:57:0) after 1m0s
[17:05:43.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:57:0)
[17:05:43.664] Timer tick, asking Buildbucket for the build status
[17:05:44.034] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:44.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:58:0) after 1m0s
[17:06:44.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:58:0)
[17:06:44.117] Timer tick, asking Buildbucket for the build status
[17:06:44.299] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:44.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:59:0) after 1m0s
[17:07:44.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:59:0)
[17:07:44.339] Timer tick, asking Buildbucket for the build status
[17:07:44.469] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:44.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:60:0) after 1m0s
[17:08:44.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:60:0)
[17:08:44.575] Timer tick, asking Buildbucket for the build status
[17:08:44.802] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:44.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:61:0) after 1m0s
[17:09:44.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:61:0)
[17:09:44.898] Timer tick, asking Buildbucket for the build status
[17:09:45.984] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:45.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:62:0) after 1m0s
[17:10:46.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:62:0)
[17:10:46.059] Timer tick, asking Buildbucket for the build status
[17:10:46.451] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:46.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:63:0) after 1m0s
[17:11:46.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:63:0)
[17:11:46.481] Timer tick, asking Buildbucket for the build status
[17:11:46.712] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:46.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:64:0) after 1m0s
[17:12:46.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:64:0)
[17:12:46.774] Timer tick, asking Buildbucket for the build status
[17:12:47.148] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:47.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:65:0) after 1m0s
[17:13:47.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:65:0)
[17:13:47.215] Timer tick, asking Buildbucket for the build status
[17:13:47.395] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:47.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:66:0) after 1m0s
[17:14:47.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:66:0)
[17:14:47.476] Timer tick, asking Buildbucket for the build status
[17:14:47.596] Build 8863369241872030816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:47.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028799303888264512:67:0) after 1m0s
[17:15:05.899] Received PubSub notification, asking Buildbucket for the build status
[17:15:06.014] Build 8863369241872030816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:15:06.014] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605633304589809",
"created_by": "project:chromium",
"created_ts": "1605629419409363",
"id": "8863369241872030816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\"}}",
"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\": \"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\", \"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-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15690, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"2f5d646c928eb989467cf53ed17e2e8d7e7ad357\", \"got_dawn_revision\": \"d98e8b70bbb198ebd665a570880ccc7b20e56198\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\", \"got_revision_cp\": \"refs/heads/master@{#828216}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"04515da400d5fbc22d852af1369c4d46bd54991e\", \"got_v8_revision\": \"15247228bbf879eab29da6c7a0b1a608fccbc1fa\", \"got_v8_revision_cp\": \"refs/heads/8.9.4@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"921d7ed3444219231c6373e69c52bf3eb2b1bdb5\", \"swarm_hashes_refs/heads/master(at){#828216}_without_patch\": {\"absl_hardening_tests\": \"54ffbd5aa9cb293f2a37afe8f355ba841608533c\", \"accessibility_unittests\": \"691e8fdf6ba4c3650104c1f95aae8037b8031ab4\", \"angle_unittests\": \"8cc45207ab173c62fdd4e289045ab917e1acfbc4\", \"app_shell_unittests\": \"8f3dde0bc835794cd43dc599603d906ab7263a08\", \"aura_unittests\": \"159f7f4b8ac5dc1630bda2501091dd8f22dc5b80\", \"base_unittests\": \"0a411918623c61c21a58ff869ba514fb20d002af\", \"base_util_unittests\": \"550f64e8530b33983988ba662fe5bc557f523cf0\", \"blink_common_unittests\": \"f7ba3b2c6917a4e41d6ce0e106062110c3fef8e2\", \"blink_fuzzer_unittests\": \"d6f3944539ef83935dd6c057b06c60fc7e2ca37b\", \"blink_heap_unittests\": \"ed4244e14c7b758db9cf7a09c0c1329942e78948\", \"blink_platform_unittests\": \"9154220746608eec41e89de90c654dfc49b829f1\", \"blink_unittests\": \"50cca8077b7871e922b1a8eaa2c92d9040cde7c3\", \"boringssl_crypto_tests\": \"3111ba0ff75a9fa7784ccdc9cc27786cdfaa3deb\", \"boringssl_ssl_tests\": \"cd2134cfcdc4d696ef71168df05f390ae00c7f6a\", \"browser_switcher_bho_unittests\": \"88139f648f30414cbd350a4456c334bdd1d1c011\", \"browser_tests\": \"f95629ac0f83b295307e48997349403e1f18829c\", \"capture_unittests\": \"284a70b88ee8ec2f44a282860626ed9b3e7b2a25\", \"cast_unittests\": \"f52fa9b5c457206826699dea22fbadf4d25c340b\", \"cc_unittests\": \"28f7810089c21628fac65eb7425fad22efe4439c\", \"chrome_app_unittests\": \"808c9e7b329cde615f239bc99c4f66ee38f27cb4\", \"chrome_cleaner_unittests\": \"d2971e656d1bcbc5a31c00b038c99f24aad1a490\", \"chrome_elf_unittests\": \"200ced72bd74716a0c6e2281e3f2c046f6703ee2\", \"chromedriver_unittests\": \"87d7b385d6f14279749b0f21544bd46fe86aceb5\", \"components_browsertests\": \"4b6218ef1d0ed60a43b9cfd6dc5cd520959d2073\", \"components_unittests\": \"49ccb84a6324e5d272f0c05d2700a197a5d4a4ac\", \"compositor_unittests\": \"bc811e5c19e3a8c86763f4bc3337838a074490de\", \"content_browsertests\": \"a128755198caa9fde6007ece40142e9f870acfb6\", \"content_unittests\": \"ebfc7ff0ba8e666210241fb37e5da16f5fdab47a\", \"courgette_unittests\": \"5d8073533b83653a64156b9cb1fe2976182e7d3a\", \"crashpad_tests\": \"b42324fbcaf83f2428ce4e2b5e0875b6cc614321\", \"cronet_tests\": \"f591d82ea8cd740aa34efd0c23741b9d964f1c7b\", \"cronet_unittests\": \"33084b1d2a93e21170bf0a92a8286fc5c2fe6406\", \"crypto_unittests\": \"8465778765d95505703511aebf4ed14eb0574cb3\", \"delayloads_unittests\": \"5543d02078b56976465a7fb97307930d931bcb01\", \"device_unittests\": \"5f360ac3bac57c0fb2b449594e12dfb759e365df\", \"display_unittests\": \"c51c3bd98eac77d12a14d981cbfac91e376b0e7a\", \"elevation_service_unittests\": \"5183d1551a333ecadcad21d0e7b44e5d072f9444\", \"events_unittests\": \"fa594b84506ff829d9afbfea5aace0881d1b37c9\", \"extensions_browsertests\": \"9cd5fffb3a37d50bef0283f2c8b3afb92d78e95c\", \"extensions_unittests\": \"af951e968f163ce01b1e0bd429d9648cf82c312c\", \"filesystem_service_unittests\": \"4b6e8ba7767adcf3d1a59727bab2955ec34a521c\", \"gcm_unit_tests\": \"cb5949dae1ee79ef86c939af02633862d252ddd2\", \"gcp_unittests\": \"9c37f6aac5571af64180863b0f554e46e99f2357\", \"gfx_unittests\": \"b6a2bdd406b401411b0fa71bb005b5d43c785cc3\", \"gin_unittests\": \"adbdd9ccf7230efb02b5eafb17e55d80210edf74\", \"google_apis_unittests\": \"5bbd96d7bc82458c849637ef21f2b0be0a0c1421\", \"gpu_unittests\": \"20b04e28bdc0b567a2cc309264d9cce39062eba5\", \"gwp_asan_unittests\": \"323cb816b80ee152510c6b8b52c346cf188dd5cc\", \"headless_browsertests\": \"4d6dd6ab0ceea88db4d231462da13fe3e9b156ba\", \"headless_unittests\": \"325382d2cc3a88625ecfd6836e5001a613a01398\", \"install_static_unittests\": \"adb2d0c4e9ecbb997e8c1824591e37d9c2ce55b0\", \"installer_util_unittests\": \"37ef6de90fd754b8b776b2adad38207ae209d512\", \"interactive_ui_tests\": \"a2c2dc79a826880ec88cac2c6dec8c89f729c8b4\", \"ipc_tests\": \"a900956b61c72db62659e095e8150f0e71c7a7b7\", \"jingle_unittests\": \"4ac826aae59347bf59de32b91227854c6f2a9460\", \"latency_unittests\": \"051f2691afea830c3e6247f2da0521119f5ec655\", \"libjingle_xmpp_unittests\": \"a3e8850fb51a1755a8c764e2960c502d930e186c\", \"liburlpattern_unittests\": \"b7f692f932e179f0c75c84dc69362e4aa46771a4\", \"media_blink_unittests\": \"36aa86d51e56082fb384a55eed551fd7d3738a71\", \"media_unittests\": \"65c9a5f41426967e27026eded3f63633e326979b\", \"message_center_unittests\": \"6bd020373fd54118594fff15242be56ce8099dc7\", \"midi_unittests\": \"ea807be8273b9f69b132824ec9d290702c7dd290\", \"mojo_core_unittests\": \"c9ee3aeda3e7d3de497f549f441fa00e7a17eb2f\", \"mojo_unittests\": \"f4b61920f5f45cf875277f5240b627fc373b07dd\", \"nacl_loader_unittests\": \"4c9698d1977a9c1c32edbfef46d4ec51a03c451f\", \"native_theme_unittests\": \"f33ed74207f58fa06039e91e93dbcf1eda7d5c5a\", \"net_unittests\": \"c3b831da752c174fd6c7ab736560ccdf7bb7ebe6\", \"notification_helper_unittests\": \"0e212a1e2ab3193684225675bce427c32d157f38\", \"pdf_unittests\": \"943e3521ad7c0d98ede9f06d7ade0f98cb7b72d4\", \"perfetto_unittests\": \"ff99e6b0c615b40670a32a55c2db5e2de792b959\", \"ppapi_unittests\": \"b5a1888d0beadd7d7a3a7daef4c95a67f998cde1\", \"printing_unittests\": \"fe0125da33d95074162639db47caaec2a83221c0\", \"remoting_unittests\": \"87458b375c59f9e1506415c6a768c9a8d3a41dc4\", \"sbox_integration_tests\": \"5aa914796f97e028a2bbd1c1591dd99995f556cf\", \"sbox_unittests\": \"c1364e0805eb2bc665c450b3c82cc9bf8cb8a6c8\", \"sbox_validation_tests\": \"3a7ea6543ef01fad8f0f85237411066b7ee75249\", \"service_manager_unittests\": \"b4a9ef089e9b7a233846af8432e6567c0d6837d7\", \"services_unittests\": \"538d4c1f62416b5d804971c8239541cb7ec7c8c7\", \"setup_unittests\": \"02d44e2853b7289fd0c83b8fa57888123f6aa7b2\", \"shell_dialogs_unittests\": \"63df90cfff11c9bd53e45f8d342335bf6578053b\", \"skia_unittests\": \"749453ab5f6888a24191d2b8f6b3b5005bfde292\", \"snapshot_unittests\": \"bb4d14c1bbd2e716b5169fe6a6fc377dd7d54cf6\", \"sql_unittests\": \"3bf3078428842960bf670c57f75da2ae07583f3c\", \"storage_unittests\": \"97f32688eef88e11b7df102d6441496531758bcf\", \"sync_integration_tests\": \"f9cb06e69fe6d149b9f3a50c3978c8733990fe1a\", \"traffic_annotation_auditor_unittests\": \"a80795f759b776ff5aac323e70f116f7accf49e7\", \"ui_base_unittests\": \"480a5b63a3695922f3135a8d37944a7d803b103c\", \"ui_touch_selection_unittests\": \"9a0f91cd7afebb597fa3c5082621060358f57a90\", \"unit_tests\": \"72e184aff80e88ebfa09c0d36d8dd83e7be45c35\", \"updater_tests\": \"e47653a7445d1189285bcfca6b206b5a0ac5afa8\", \"url_unittests\": \"9bb85bb79ba5d2978c280fd8d966991fd0b63f37\", \"views_unittests\": \"614ac357108990a64dbb34819a35de31c294bd5f\", \"viz_unittests\": \"664f68ea99c45ab579265d609f801ece5ae32cbe\", \"vr_common_unittests\": \"256be974d598b868d771e19e51b7a61b7efdd898\", \"vr_pixeltests\": \"769cf641b65e2afcde28183c56bbff327d85a3a4\", \"weblayer_browsertests\": \"1da3bf465fbc416536ee22f11af6a5668b9e4cf1\", \"weblayer_unittests\": \"1f122420c67f2c9ed1ad7184e5a20a34dcf3151e\", \"wm_unittests\": \"3c635cc8ba16d57706496615a37da49083ff7d32\", \"wtf_unittests\": \"13dcc6969fa422cb78a1c88d171810de28ae9c80\", \"zlib_unittests\": \"656b4749351a82aabf18434d75fbed455df10afb\", \"zucchini_unittests\": \"788e949620c50cd9d00c693e6e20cab0380fe6fe\"}}, \"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-fw95\"], \"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\": [\"5632-2e614e1\"], \"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": "1605629430448838",
"status": "COMPLETED",
"status_changed_ts": "1605633305391981",
"tags": [
"build_address:luci.chromium.ci/win-asan/15690",
"builder:win-asan",
"buildset:commit/git/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/921d7ed3444219231c6373e69c52bf3eb2b1bdb5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028799303888264512",
"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/8863369241872030816/+/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:4fefca3393b97910",
"user_agent:luci-scheduler"
],
"updated_ts": "1605633305397490",
"url": "https://ci.chromium.org/b/8863369241872030816",
"utcnow_ts": "1605633305995602"
}
}
[17:15:06.015] Invocation finished in 1h4m47.912053402s with status SUCCEEDED