[23:08:14.678] New invocation is queued and will start shortly
[23:08:15.733] Starting the invocation (attempt 1)
[23:08:15.773] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:08:15.775] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:08:15.775] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018354358107255424",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8b3180bf347f3c0ef87711f7946244f51e14aa61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018354358107255424",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"gitiles_ref:refs/heads/master"
]
}
[23:08:16.482] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615590495890332",
"id": "8852924296140212800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b3180bf347f3c0ef87711f7946244f51e14aa61\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615590496364412",
"tags": [
"build_address:luci.chromium.ci/win-asan/17779",
"builder:win-asan",
"buildset:commit/git/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018354358107255424",
"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/8852924296140212800/+/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": "1615590496364515",
"url": "https://ci.chromium.org/b/8852924296140212800",
"utcnow_ts": "1615590496475562"
}
}
[23:08:16.482] Task URL: https://ci.chromium.org/b/8852924296140212800
[23:08:16.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:2:0) after 1m0s
[23:08:21.205] Received PubSub notification, asking Buildbucket for the build status
[23:08:21.429] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:16.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:2:0)
[23:09:16.585] Timer tick, asking Buildbucket for the build status
[23:09:16.661] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:16.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:4:0) after 1m0s
[23:10:16.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:4:0)
[23:10:16.656] Timer tick, asking Buildbucket for the build status
[23:10:16.751] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:16.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:5:0) after 1m0s
[23:11:16.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:5:0)
[23:11:16.772] Timer tick, asking Buildbucket for the build status
[23:11:16.838] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:16.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:6:0) after 1m0s
[23:12:16.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:6:0)
[23:12:16.919] Timer tick, asking Buildbucket for the build status
[23:12:17.047] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:17.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:7:0) after 1m0s
[23:13:17.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:7:0)
[23:13:17.089] Timer tick, asking Buildbucket for the build status
[23:13:17.165] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:8:0) after 1m0s
[23:14:17.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:8:0)
[23:14:17.177] Timer tick, asking Buildbucket for the build status
[23:14:17.323] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:17.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:9:0) after 1m0s
[23:15:17.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:9:0)
[23:15:17.454] Timer tick, asking Buildbucket for the build status
[23:15:17.936] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:17.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:10:0) after 1m0s
[23:16:17.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:10:0)
[23:16:17.969] Timer tick, asking Buildbucket for the build status
[23:16:18.231] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:18.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:11:0) after 1m0s
[23:17:18.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:11:0)
[23:17:18.226] Timer tick, asking Buildbucket for the build status
[23:17:18.463] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:18.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:12:0) after 1m0s
[23:18:18.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:12:0)
[23:18:18.502] Timer tick, asking Buildbucket for the build status
[23:18:18.581] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:18.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:13:0) after 1m0s
[23:19:18.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:13:0)
[23:19:18.616] Timer tick, asking Buildbucket for the build status
[23:19:18.745] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:18.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:14:0) after 1m0s
[23:20:18.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:14:0)
[23:20:18.782] Timer tick, asking Buildbucket for the build status
[23:20:18.906] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:18.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:15:0) after 1m0s
[23:21:18.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:15:0)
[23:21:18.924] Timer tick, asking Buildbucket for the build status
[23:21:18.997] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:18.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:16:0) after 1m0s
[23:22:19.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:16:0)
[23:22:19.016] Timer tick, asking Buildbucket for the build status
[23:22:19.094] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:17:0) after 1m0s
[23:23:19.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:17:0)
[23:23:19.120] Timer tick, asking Buildbucket for the build status
[23:23:19.404] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:19.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:18:0) after 1m0s
[23:24:19.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:18:0)
[23:24:19.476] Timer tick, asking Buildbucket for the build status
[23:24:19.581] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:19.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:19:0) after 1m0s
[23:25:19.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:19:0)
[23:25:19.598] Timer tick, asking Buildbucket for the build status
[23:25:19.666] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:19.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:20:0) after 1m0s
[23:26:19.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:20:0)
[23:26:19.727] Timer tick, asking Buildbucket for the build status
[23:26:20.047] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:20.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:21:0) after 1m0s
[23:27:20.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:21:0)
[23:27:20.053] Timer tick, asking Buildbucket for the build status
[23:27:21.344] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:21.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:22:0) after 1m0s
[23:28:21.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:22:0)
[23:28:21.379] Timer tick, asking Buildbucket for the build status
[23:28:21.562] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:21.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:23:0) after 1m0s
[23:29:21.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:23:0)
[23:29:21.551] Timer tick, asking Buildbucket for the build status
[23:29:21.633] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:21.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:24:0) after 1m0s
[23:30:21.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:24:0)
[23:30:21.641] Timer tick, asking Buildbucket for the build status
[23:30:21.756] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:21.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:25:0) after 1m0s
[23:31:21.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:25:0)
[23:31:21.802] Timer tick, asking Buildbucket for the build status
[23:31:22.162] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:22.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:26:0) after 1m0s
[23:32:22.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:26:0)
[23:32:22.234] Timer tick, asking Buildbucket for the build status
[23:32:22.352] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:22.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:27:0) after 1m0s
[23:33:22.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:27:0)
[23:33:22.386] Timer tick, asking Buildbucket for the build status
[23:33:22.858] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:22.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:28:0) after 1m0s
[23:34:22.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:28:0)
[23:34:22.905] Timer tick, asking Buildbucket for the build status
[23:34:23.047] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:23.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:29:0) after 1m0s
[23:35:23.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:29:0)
[23:35:23.048] Timer tick, asking Buildbucket for the build status
[23:35:23.205] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:23.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:30:0) after 1m0s
[23:36:23.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:30:0)
[23:36:23.239] Timer tick, asking Buildbucket for the build status
[23:36:23.342] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:23.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:31:0) after 1m0s
[23:37:23.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:31:0)
[23:37:23.362] Timer tick, asking Buildbucket for the build status
[23:37:23.453] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:23.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:32:0) after 1m0s
[23:38:23.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:32:0)
[23:38:23.468] Timer tick, asking Buildbucket for the build status
[23:38:24.227] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:24.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:33:0) after 1m0s
[23:39:24.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:33:0)
[23:39:24.255] Timer tick, asking Buildbucket for the build status
[23:39:24.356] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:24.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:34:0) after 1m0s
[23:40:24.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:34:0)
[23:40:24.405] Timer tick, asking Buildbucket for the build status
[23:40:24.587] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:24.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:35:0) after 1m0s
[23:41:24.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:35:0)
[23:41:24.633] Timer tick, asking Buildbucket for the build status
[23:41:24.896] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:24.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:36:0) after 1m0s
[23:42:25.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:36:0)
[23:42:25.063] Timer tick, asking Buildbucket for the build status
[23:42:25.455] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:37:0) after 1m0s
[23:43:25.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:37:0)
[23:43:25.564] Timer tick, asking Buildbucket for the build status
[23:43:25.654] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:25.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:38:0) after 1m0s
[23:44:25.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:38:0)
[23:44:25.721] Timer tick, asking Buildbucket for the build status
[23:44:26.162] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:26.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:39:0) after 1m0s
[23:45:26.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:39:0)
[23:45:26.198] Timer tick, asking Buildbucket for the build status
[23:45:26.302] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:40:0) after 1m0s
[23:46:26.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:40:0)
[23:46:26.311] Timer tick, asking Buildbucket for the build status
[23:46:26.585] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:26.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:41:0) after 1m0s
[23:47:26.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:41:0)
[23:47:26.655] Timer tick, asking Buildbucket for the build status
[23:47:26.754] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:26.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:42:0) after 1m0s
[23:48:26.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:42:0)
[23:48:26.779] Timer tick, asking Buildbucket for the build status
[23:48:27.033] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:27.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:43:0) after 1m0s
[23:49:27.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:43:0)
[23:49:27.109] Timer tick, asking Buildbucket for the build status
[23:49:27.312] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:27.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:44:0) after 1m0s
[23:50:27.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:44:0)
[23:50:27.321] Timer tick, asking Buildbucket for the build status
[23:50:27.386] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:27.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:45:0) after 1m0s
[23:51:27.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:45:0)
[23:51:27.459] Timer tick, asking Buildbucket for the build status
[23:51:27.559] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:27.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:46:0) after 1m0s
[23:52:27.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:46:0)
[23:52:27.580] Timer tick, asking Buildbucket for the build status
[23:52:27.906] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:27.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:47:0) after 1m0s
[23:53:27.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:47:0)
[23:53:27.980] Timer tick, asking Buildbucket for the build status
[23:53:28.092] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:28.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:48:0) after 1m0s
[23:54:28.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:48:0)
[23:54:28.120] Timer tick, asking Buildbucket for the build status
[23:54:28.305] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:28.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:49:0) after 1m0s
[23:55:28.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:49:0)
[23:55:28.440] Timer tick, asking Buildbucket for the build status
[23:55:28.509] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:28.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:50:0) after 1m0s
[23:56:28.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:50:0)
[23:56:28.497] Timer tick, asking Buildbucket for the build status
[23:56:28.571] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:28.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:51:0) after 1m0s
[23:57:28.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:51:0)
[23:57:28.596] Timer tick, asking Buildbucket for the build status
[23:57:28.700] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:28.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:52:0) after 1m0s
[23:58:28.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:52:0)
[23:58:28.725] Timer tick, asking Buildbucket for the build status
[23:58:28.827] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:28.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:53:0) after 1m0s
[23:59:28.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:53:0)
[23:59:28.930] Timer tick, asking Buildbucket for the build status
[23:59:29.241] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:29.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:54:0) after 1m0s
[00:00:29.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:54:0)
[00:00:29.359] Timer tick, asking Buildbucket for the build status
[00:00:31.585] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:31.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:55:0) after 1m0s
[00:01:31.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:55:0)
[00:01:31.666] Timer tick, asking Buildbucket for the build status
[00:01:31.768] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:31.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:56:0) after 1m0s
[00:02:31.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:56:0)
[00:02:31.820] Timer tick, asking Buildbucket for the build status
[00:02:31.889] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:31.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:57:0) after 1m0s
[00:03:31.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:57:0)
[00:03:31.946] Timer tick, asking Buildbucket for the build status
[00:03:32.059] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:32.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:58:0) after 1m0s
[00:04:32.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:58:0)
[00:04:32.057] Timer tick, asking Buildbucket for the build status
[00:04:32.135] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:32.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:59:0) after 1m0s
[00:05:32.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:59:0)
[00:05:32.155] Timer tick, asking Buildbucket for the build status
[00:05:32.545] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:32.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:60:0) after 1m0s
[00:06:32.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:60:0)
[00:06:32.609] Timer tick, asking Buildbucket for the build status
[00:06:32.810] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:32.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:61:0) after 1m0s
[00:07:32.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:61:0)
[00:07:32.830] Timer tick, asking Buildbucket for the build status
[00:07:32.959] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:32.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:62:0) after 1m0s
[00:08:33.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:62:0)
[00:08:33.006] Timer tick, asking Buildbucket for the build status
[00:08:33.531] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:33.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:63:0) after 1m0s
[00:09:33.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:63:0)
[00:09:33.600] Timer tick, asking Buildbucket for the build status
[00:09:33.925] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:33.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:64:0) after 1m0s
[00:10:33.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:64:0)
[00:10:33.919] Timer tick, asking Buildbucket for the build status
[00:10:34.021] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:34.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:65:0) after 1m0s
[00:11:34.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:65:0)
[00:11:34.048] Timer tick, asking Buildbucket for the build status
[00:11:34.166] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:34.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:66:0) after 1m0s
[00:12:34.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:66:0)
[00:12:34.258] Timer tick, asking Buildbucket for the build status
[00:12:34.608] Build 8852924296140212800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:34.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018354358107255424:67:0) after 1m0s
[00:12:37.052] Received PubSub notification, asking Buildbucket for the build status
[00:12:37.154] Build 8852924296140212800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:12:37.156] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615594355711240",
"created_by": "project:chromium",
"created_ts": "1615590495890332",
"id": "8852924296140212800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b3180bf347f3c0ef87711f7946244f51e14aa61\"}}",
"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\": \"8b3180bf347f3c0ef87711f7946244f51e14aa61\", \"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-zecp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17779, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"41a2672f43fb125e83b4942133e487995da9bf7c\", \"got_dawn_revision\": \"c53ea046a603d5085268584f16bfdf61586f4c4c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8b3180bf347f3c0ef87711f7946244f51e14aa61\", \"got_revision_cp\": \"refs/heads/master@{#862575}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a5619c014b66c2a5c693c5777684da012f55d190\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"is_cached\": true, \"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\": \"8b3180bf347f3c0ef87711f7946244f51e14aa61\", \"swarm_hashes_refs/heads/master(at){#862575}_without_patch\": {\"absl_hardening_tests\": \"936d0dd7c64c0f1b38033254e100c2b023fec043\", \"accessibility_unittests\": \"0518e6781834b666f85cc0f154c1b61a652bb6bc\", \"angle_unittests\": \"6fbb2810f9454b6ab1cc01938b9a2caee02c8d55\", \"app_shell_unittests\": \"00db15589138808e464580a6c7ca16ec13c98487\", \"aura_unittests\": \"822c482d3175654d53de640b040d59577f013b32\", \"base_unittests\": \"879a5f02fde36752fb1af9a2bcecf97d72c93e39\", \"base_util_unittests\": \"ed3f460df789604e33e7aa33ba0250e65781b775\", \"blink_common_unittests\": \"6adcc34f51b89660d340418031257eb2b1a7bd17\", \"blink_fuzzer_unittests\": \"b31fbc87f608a5e9164e7aa3c34facfc18534919\", \"blink_heap_unittests\": \"3067062ca6d9e43d3e8ec5153eb38d864d780961\", \"blink_platform_unittests\": \"467b7ceda1b735006759d5e0cb30fb529a0cf850\", \"blink_unittests\": \"52917ef00b10cfdc4155ce179dea517356e605eb\", \"boringssl_crypto_tests\": \"48116f73155803b3adbfbff3cb583975da7f25c8\", \"boringssl_ssl_tests\": \"b03c50f14055d33e112a72355586775436c4a7ff\", \"browser_switcher_bho_unittests\": \"5ef088f8f246dad48142fe7fa207f649a5718e7b\", \"browser_tests\": \"387ad606dc6e5b593a2cdbcfda699546d13c19f6\", \"capture_unittests\": \"9a510a4623b8787bc1da74f0fb640b751e440fcc\", \"cast_unittests\": \"75a4c3963d5bf6257f4a19831c6d1b042879c3e4\", \"cc_unittests\": \"320c524ceef0314756789e9d05ceb4ae3e3eff2c\", \"chrome_app_unittests\": \"f69152f170889d03ed42271facd2eaae016a3a45\", \"chrome_cleaner_unittests\": \"99fa3f8d410b5643078620e43cbd1240897f933b\", \"chrome_elf_unittests\": \"4233cd256616b28933bcae8b24595b1d086449de\", \"chromedriver_unittests\": \"7b683c0244710db77fb9b8b8d796631dc7de3656\", \"color_unittests\": \"d98babaa7975354b586f42bc8d99c3ab51da0a4e\", \"components_browsertests\": \"ebe373285af7e85922d3a8df3df6cf94165ff112\", \"components_unittests\": \"7d450b2eb96f3603097b42586752d4848aa05491\", \"compositor_unittests\": \"5c757f8be007d1a503b2ef65220ba486536d4e5a\", \"content_browsertests\": \"c2a4908e00aee160282bf66c2373dfe84956c713\", \"content_unittests\": \"3ef9e3e7704873033985e901593705e664322097\", \"courgette_unittests\": \"49d75bdf43f6b751ea90f397254ec6783366377f\", \"crashpad_tests\": \"f7b301eb0cf9ffb1ba22f33c44f446255abc8b2f\", \"cronet_tests\": \"a6a6488b60db72981087098b41f37c2a4dd3a81c\", \"cronet_unittests\": \"60f8369bb7300364897d653c8e685939f77f3141\", \"crypto_unittests\": \"bf2e08c4e92bc611b47d57881f522a6f2c5b6d7d\", \"delayloads_unittests\": \"bad277e34a4ab84d9c2b74cb817703caff9a3e51\", \"device_unittests\": \"e320fbe6422e2a13eb626a931a47e88bfb0e7528\", \"display_unittests\": \"8b13fb2321dc01a5c217cb09ae061433e5a62a1f\", \"elevation_service_unittests\": \"b4df51d40a3ea6d471a520d2b5becfd9d56dbddd\", \"events_unittests\": \"1c35495cdb0168f68270d151d716501cc4136ddb\", \"extensions_browsertests\": \"fe3c7a0b97033b5f242493ae3b95e5c6ad441562\", \"extensions_unittests\": \"111c7d3cf078d8553e493ef4e6bbc2b5501ac30a\", \"filesystem_service_unittests\": \"44e2818b97840fbf3ff5a55c4b628e3d0bbad00a\", \"gcm_unit_tests\": \"2b3080e5cac981f837a0ae1c6e1fc9d5a14f4f25\", \"gcp_unittests\": \"fec31bbd1ab7a67505a399e7d9d1547fdba42713\", \"gfx_unittests\": \"a69e36324ff0b12fac3058f9d89639c9aba4560a\", \"gin_unittests\": \"440c36d64641528ccd8360a8655759ac332db5db\", \"google_apis_unittests\": \"372cb13ee996ef03e9569d150c0a71503844cd7c\", \"gpu_unittests\": \"dde66a27f9357ac51fe6475eec754718ee39fee4\", \"gwp_asan_unittests\": \"4a234ce89939697e2b86eb806c2dd073f71d4ed0\", \"headless_browsertests\": \"ef86735693bf15a2556e3c3ef2a3debdd74735fa\", \"headless_unittests\": \"d0d447cfeb7fa4a971f9e9d3d3b7a89da6fce18f\", \"install_static_unittests\": \"317facf15094077d66257477ad7a416f3f2c4486\", \"installer_util_unittests\": \"4b7ab9f9970776451f2df062abafe19835f5c93e\", \"interactive_ui_tests\": \"70d043389f07db7018151ef22a5c42d42ec8fe37\", \"ipc_tests\": \"9c320ffb2945e88832b3b4cf2694c9a77f6056c1\", \"jingle_unittests\": \"b4af69dd91a996844d9f52f536eb1e73446e69fb\", \"latency_unittests\": \"661eea2c4a940480a100708d3346ed507d0dc8b8\", \"libjingle_xmpp_unittests\": \"1b73f3ea8c879dc1250e80f25c9a50e14cef73cd\", \"liburlpattern_unittests\": \"6433462f30a880c19bd0e8c45e9d1dccc2884be4\", \"media_blink_unittests\": \"5baf6ffa254972389f6694830bde997bec5ff9cd\", \"media_unittests\": \"a6fca4444f2dbb4dee9b0d3576afa434229995c4\", \"message_center_unittests\": \"9351817fb1e157985554a9808d3edfd2917d5935\", \"midi_unittests\": \"74f234ace5a36edfced7844963f7f8dba6c4331c\", \"mojo_core_unittests\": \"de06a3440909647503e9d40caef1ec81e7bc1e26\", \"mojo_unittests\": \"224a3b77a4d536fc061e2f27120da5788c96d9ff\", \"nacl_loader_unittests\": \"2e5b0a68cc339938c547e6bd86259fa71b11ab6c\", \"native_theme_unittests\": \"52adf084d75172902f2945ee8d9753e0d260250e\", \"net_unittests\": \"53be76be44c4443c032e41afeab7d7ffe2183a64\", \"notification_helper_unittests\": \"2b662b68a84342416c7e86ae25f2b4227cc63417\", \"pdf_unittests\": \"64b155961c673bceddc75faa6224d6e35b3fc71a\", \"perfetto_unittests\": \"c71a85c5f9c575bd063d6d79c285d1e95947cbdb\", \"ppapi_unittests\": \"a48882533a9b629402b1f07e97bc1646a91913d8\", \"printing_unittests\": \"cbff22757fcf588370f10c26795a5db2a1a7f9b2\", \"remoting_unittests\": \"f0ad9c0069a5fb1b6a2a937111ce75e984342d22\", \"sbox_integration_tests\": \"48f038212544dcd93c2535d3937c73358a5430e0\", \"sbox_unittests\": \"58c41596f7257d3377087ecb2586f36894edfe73\", \"sbox_validation_tests\": \"fde24e102bd7947044e113ef1f6515bc5700ac37\", \"service_manager_unittests\": \"09b273247026f1a9cd179e0850069e9ade0816f9\", \"services_unittests\": \"7663c0ae1a0e698ae1bf7e4adc6922984323175d\", \"setup_unittests\": \"feac415d1aaf4b6e60b834ca196f64f5e7588af5\", \"shell_dialogs_unittests\": \"c615031177c8330bc6d1fc14e51ce4ce370b83d1\", \"skia_unittests\": \"d3a821da3c797ee528b48342618f198f37a46351\", \"snapshot_unittests\": \"7d0f808b8bf68f33f6b573248e8b8c7f830fbb9b\", \"sql_unittests\": \"551259d0d96367b3b08401703f2a07c22a3fcff9\", \"storage_unittests\": \"cd0229a5def32a21b8f34f1efe3193e93dcb1045\", \"sync_integration_tests\": \"121358cb412dd8645033dc86370b649cf40375b4\", \"traffic_annotation_auditor_unittests\": \"8a37abac64bbe7d553b55f61562626b1fc5c1520\", \"ui_base_unittests\": \"c8514d4ee66c220217356c869bd7dd129247bca8\", \"ui_touch_selection_unittests\": \"3f6367a81734150cfa52ef37efabd2a959cecb4a\", \"unit_tests\": \"74f66f4273e25fe53af1cad8e1f0838dd750db95\", \"updater_tests\": \"9a4041171c33883ae5b7ebb5547fa682d73a6128\", \"url_unittests\": \"07c998e6808ffcc4e2cb10f99b3644675a9316c4\", \"views_unittests\": \"5f1a01e94802a3e60e68394beda2aee740eb1acc\", \"viz_unittests\": \"b1940e577987095f93e25a8fbcba8b0bb2aa8769\", \"vr_common_unittests\": \"66e1a216cd4e8dc7ffbdd2509184705ea0bb947d\", \"vr_pixeltests\": \"50f3f6c90c2e428aea5b6af8e981cba5b5fae5e2\", \"weblayer_browsertests\": \"2610fd376798d972356d7d700d127f74129e11cc\", \"weblayer_unittests\": \"d9f826cbff6ae249c96298d0d51a2be18fef996a\", \"wm_unittests\": \"1661d1f450c038e710e4bee40602d25f57f40690\", \"wtf_unittests\": \"4af72989f988326b69973b067742fcdb039177e0\", \"zlib_unittests\": \"c340da81e5fb0a6f815974026c10b56fa5c153d6\", \"zucchini_unittests\": \"25004f2a1b8e10536ea7709d8efd7d2007db9cac\"}}, \"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-2-zecp\"], \"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\": [\"5754-8c4f760\"], \"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": "1615590499907543",
"status": "COMPLETED",
"status_changed_ts": "1615594356583623",
"tags": [
"build_address:luci.chromium.ci/win-asan/17779",
"builder:win-asan",
"buildset:commit/git/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b3180bf347f3c0ef87711f7946244f51e14aa61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018354358107255424",
"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/8852924296140212800/+/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:5241842149dc7110",
"user_agent:luci-scheduler"
],
"updated_ts": "1615594356589611",
"url": "https://ci.chromium.org/b/8852924296140212800",
"utcnow_ts": "1615594357138699"
}
}
[00:12:37.157] Invocation finished in 1h4m22.526749111s with status SUCCEEDED