[10:18:19.128] New invocation is queued and will start shortly
[10:18:20.234] Starting the invocation (attempt 1)
[10:18:20.294] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:18:20.294] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:18:20.294] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021845482342505856",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021845482342505856",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"gitiles_ref:refs/heads/master"
]
}
[10:18:21.958] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612261100439647",
"id": "8856415420304047568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612261101593017",
"tags": [
"build_address:luci.chromium.ci/win-asan/17128",
"builder:win-asan",
"buildset:commit/git/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021845482342505856",
"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/8856415420304047568/+/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": "1612261101593235",
"url": "https://ci.chromium.org/b/8856415420304047568",
"utcnow_ts": "1612261101926971"
}
}
[10:18:21.959] Task URL: https://ci.chromium.org/b/8856415420304047568
[10:18:21.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:2:0) after 1m0s
[10:18:25.669] Received PubSub notification, asking Buildbucket for the build status
[10:18:25.739] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:21.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:2:0)
[10:19:21.978] Timer tick, asking Buildbucket for the build status
[10:19:22.307] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:22.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:4:0) after 1m0s
[10:20:22.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:4:0)
[10:20:22.334] Timer tick, asking Buildbucket for the build status
[10:20:22.576] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:22.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:5:0) after 1m0s
[10:21:22.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:5:0)
[10:21:22.602] Timer tick, asking Buildbucket for the build status
[10:21:22.849] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:22.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:6:0) after 1m0s
[10:22:22.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:6:0)
[10:22:22.911] Timer tick, asking Buildbucket for the build status
[10:22:23.003] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:23.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:7:0) after 1m0s
[10:23:23.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:7:0)
[10:23:23.037] Timer tick, asking Buildbucket for the build status
[10:23:23.128] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:23.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:8:0) after 1m0s
[10:24:23.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:8:0)
[10:24:23.148] Timer tick, asking Buildbucket for the build status
[10:24:23.237] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:23.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:9:0) after 1m0s
[10:25:23.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:9:0)
[10:25:23.284] Timer tick, asking Buildbucket for the build status
[10:25:23.389] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:23.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:10:0) after 1m0s
[10:26:23.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:10:0)
[10:26:23.415] Timer tick, asking Buildbucket for the build status
[10:26:23.512] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:23.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:11:0) after 1m0s
[10:27:23.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:11:0)
[10:27:23.554] Timer tick, asking Buildbucket for the build status
[10:27:23.640] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:23.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:12:0) after 1m0s
[10:28:23.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:12:0)
[10:28:23.670] Timer tick, asking Buildbucket for the build status
[10:28:23.780] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:23.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:13:0) after 1m0s
[10:29:23.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:13:0)
[10:29:23.839] Timer tick, asking Buildbucket for the build status
[10:29:23.966] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:23.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:14:0) after 1m0s
[10:30:24.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:14:0)
[10:30:24.124] Timer tick, asking Buildbucket for the build status
[10:30:24.694] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:24.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:15:0) after 1m0s
[10:31:24.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:15:0)
[10:31:24.706] Timer tick, asking Buildbucket for the build status
[10:31:24.912] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:24.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:16:0) after 1m0s
[10:32:24.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:16:0)
[10:32:24.993] Timer tick, asking Buildbucket for the build status
[10:32:25.092] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:25.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:17:0) after 1m0s
[10:33:25.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:17:0)
[10:33:25.173] Timer tick, asking Buildbucket for the build status
[10:33:25.543] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:25.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:18:0) after 1m0s
[10:34:25.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:18:0)
[10:34:25.559] Timer tick, asking Buildbucket for the build status
[10:34:25.636] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:19:0) after 1m0s
[10:35:25.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:19:0)
[10:35:25.681] Timer tick, asking Buildbucket for the build status
[10:35:25.813] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:25.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:20:0) after 1m0s
[10:36:25.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:20:0)
[10:36:25.858] Timer tick, asking Buildbucket for the build status
[10:36:25.959] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:25.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:21:0) after 1m0s
[10:37:26.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:21:0)
[10:37:26.006] Timer tick, asking Buildbucket for the build status
[10:37:26.549] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:26.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:22:0) after 1m0s
[10:38:26.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:22:0)
[10:38:26.649] Timer tick, asking Buildbucket for the build status
[10:38:26.768] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:26.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:23:0) after 1m0s
[10:39:26.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:23:0)
[10:39:26.754] Timer tick, asking Buildbucket for the build status
[10:39:26.864] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:26.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:24:0) after 1m0s
[10:40:26.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:24:0)
[10:40:26.848] Timer tick, asking Buildbucket for the build status
[10:40:26.961] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:26.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:25:0) after 1m0s
[10:41:26.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:25:0)
[10:41:26.997] Timer tick, asking Buildbucket for the build status
[10:41:27.085] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:27.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:26:0) after 1m0s
[10:42:27.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:26:0)
[10:42:27.108] Timer tick, asking Buildbucket for the build status
[10:42:27.608] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:27:0) after 1m0s
[10:43:27.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:27:0)
[10:43:27.631] Timer tick, asking Buildbucket for the build status
[10:43:27.736] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:27.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:28:0) after 1m0s
[10:44:27.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:28:0)
[10:44:27.757] Timer tick, asking Buildbucket for the build status
[10:44:27.859] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:27.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:29:0) after 1m0s
[10:45:27.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:29:0)
[10:45:27.936] Timer tick, asking Buildbucket for the build status
[10:45:28.289] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:28.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:30:0) after 1m0s
[10:46:28.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:30:0)
[10:46:28.284] Timer tick, asking Buildbucket for the build status
[10:46:28.737] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:28.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:31:0) after 1m0s
[10:47:28.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:31:0)
[10:47:28.760] Timer tick, asking Buildbucket for the build status
[10:47:28.903] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:28.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:32:0) after 1m0s
[10:48:28.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:32:0)
[10:48:28.924] Timer tick, asking Buildbucket for the build status
[10:48:29.099] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:29.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:33:0) after 1m0s
[10:49:29.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:33:0)
[10:49:29.144] Timer tick, asking Buildbucket for the build status
[10:49:29.697] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:29.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:34:0) after 1m0s
[10:50:29.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:34:0)
[10:50:29.747] Timer tick, asking Buildbucket for the build status
[10:50:30.312] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:30.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:35:0) after 1m0s
[10:51:30.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:35:0)
[10:51:30.335] Timer tick, asking Buildbucket for the build status
[10:51:31.045] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:31.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:36:0) after 1m0s
[10:52:31.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:36:0)
[10:52:31.090] Timer tick, asking Buildbucket for the build status
[10:52:31.343] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:31.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:37:0) after 1m0s
[10:53:31.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:37:0)
[10:53:31.354] Timer tick, asking Buildbucket for the build status
[10:53:31.529] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:31.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:38:0) after 1m0s
[10:54:31.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:38:0)
[10:54:31.530] Timer tick, asking Buildbucket for the build status
[10:54:31.661] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:31.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:39:0) after 1m0s
[10:55:31.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:39:0)
[10:55:31.682] Timer tick, asking Buildbucket for the build status
[10:55:31.838] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:31.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:40:0) after 1m0s
[10:56:31.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:40:0)
[10:56:31.868] Timer tick, asking Buildbucket for the build status
[10:56:32.441] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:32.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:41:0) after 1m0s
[10:57:32.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:41:0)
[10:57:32.486] Timer tick, asking Buildbucket for the build status
[10:57:32.625] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:32.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:42:0) after 1m0s
[10:58:32.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:42:0)
[10:58:32.711] Timer tick, asking Buildbucket for the build status
[10:58:33.417] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:33.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:43:0) after 1m0s
[10:59:33.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:43:0)
[10:59:33.450] Timer tick, asking Buildbucket for the build status
[10:59:33.533] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:33.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:44:0) after 1m0s
[11:00:33.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:44:0)
[11:00:33.607] Timer tick, asking Buildbucket for the build status
[11:00:33.897] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:33.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:45:0) after 1m0s
[11:01:33.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:45:0)
[11:01:33.916] Timer tick, asking Buildbucket for the build status
[11:01:34.098] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:34.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:46:0) after 1m0s
[11:02:34.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:46:0)
[11:02:34.212] Timer tick, asking Buildbucket for the build status
[11:02:34.278] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:34.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:47:0) after 1m0s
[11:03:34.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:47:0)
[11:03:34.329] Timer tick, asking Buildbucket for the build status
[11:03:34.709] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:34.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:48:0) after 1m0s
[11:04:34.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:48:0)
[11:04:34.768] Timer tick, asking Buildbucket for the build status
[11:04:35.162] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:35.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:49:0) after 1m0s
[11:05:35.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:49:0)
[11:05:35.187] Timer tick, asking Buildbucket for the build status
[11:05:35.404] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:35.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:50:0) after 1m0s
[11:06:35.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:50:0)
[11:06:35.464] Timer tick, asking Buildbucket for the build status
[11:06:35.558] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:35.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:51:0) after 1m0s
[11:07:35.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:51:0)
[11:07:35.604] Timer tick, asking Buildbucket for the build status
[11:07:35.867] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:35.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:52:0) after 1m0s
[11:08:35.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:52:0)
[11:08:35.870] Timer tick, asking Buildbucket for the build status
[11:08:36.929] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:36.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:53:0) after 1m0s
[11:09:36.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:53:0)
[11:09:36.976] Timer tick, asking Buildbucket for the build status
[11:09:37.309] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:37.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:54:0) after 1m0s
[11:10:37.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:54:0)
[11:10:37.424] Timer tick, asking Buildbucket for the build status
[11:10:37.631] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:37.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:55:0) after 1m0s
[11:11:37.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:55:0)
[11:11:37.687] Timer tick, asking Buildbucket for the build status
[11:11:37.749] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:37.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:56:0) after 1m0s
[11:12:37.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:56:0)
[11:12:37.851] Timer tick, asking Buildbucket for the build status
[11:12:38.016] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:38.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:57:0) after 1m0s
[11:13:38.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:57:0)
[11:13:38.107] Timer tick, asking Buildbucket for the build status
[11:13:38.514] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:38.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:58:0) after 1m0s
[11:14:38.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:58:0)
[11:14:38.544] Timer tick, asking Buildbucket for the build status
[11:14:38.837] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:38.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:59:0) after 1m0s
[11:15:38.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:59:0)
[11:15:38.855] Timer tick, asking Buildbucket for the build status
[11:15:38.933] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:38.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:60:0) after 1m0s
[11:16:38.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:60:0)
[11:16:38.952] Timer tick, asking Buildbucket for the build status
[11:16:39.045] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:39.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:61:0) after 1m0s
[11:17:39.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:61:0)
[11:17:39.108] Timer tick, asking Buildbucket for the build status
[11:17:39.508] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:39.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:62:0) after 1m0s
[11:18:39.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:62:0)
[11:18:39.530] Timer tick, asking Buildbucket for the build status
[11:18:39.784] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:39.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:63:0) after 1m0s
[11:19:39.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:63:0)
[11:19:39.795] Timer tick, asking Buildbucket for the build status
[11:19:39.905] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:39.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:64:0) after 1m0s
[11:20:39.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:64:0)
[11:20:39.994] Timer tick, asking Buildbucket for the build status
[11:20:40.671] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:40.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:65:0) after 1m0s
[11:21:40.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:65:0)
[11:21:40.737] Timer tick, asking Buildbucket for the build status
[11:21:40.894] Build 8856415420304047568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:40.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021845482342505856:66:0) after 1m0s
[11:21:49.388] Received PubSub notification, asking Buildbucket for the build status
[11:21:49.468] Build 8856415420304047568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:21:49.468] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612264907816071",
"created_by": "project:chromium",
"created_ts": "1612261100439647",
"id": "8856415420304047568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\"}}",
"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\": \"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\", \"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-8-uuz6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17128, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"7e81056a54691bea83cbea37cb6a94dadb7c4bda\", \"got_dawn_revision\": \"9a2174a37cfdc771a9c7a494e6fb773c541bd941\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\", \"got_revision_cp\": \"refs/heads/master@{#849532}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"079f7c53751ac589cf05880522b3fa2fcb5b5645\", \"got_v8_revision_cp\": \"refs/heads/9.0.109@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c9715bf00c03a07e71810a6bddc0d04c86bbadd6\", \"swarm_hashes_refs/heads/master(at){#849532}_without_patch\": {\"absl_hardening_tests\": \"1726408473e9e22f85bc6812521f41950f255d8d\", \"accessibility_unittests\": \"57fb5974393b9d89ac1edb69f9ec9269aa583159\", \"angle_unittests\": \"ee0b23d7277055a7b8e76770ccb710e296e894e9\", \"app_shell_unittests\": \"42aadc90e1a6a4c7f54b10cb7c635d9bd1a08d5c\", \"aura_unittests\": \"0f6ece40f8e41ed4dbeba94d48ba5d69dacac192\", \"base_unittests\": \"7f4f2668dc7121d3c522b6ee67b0781b17bd935a\", \"base_util_unittests\": \"d911f30504db7f149eec9de9565facfcfd7ad9fc\", \"blink_common_unittests\": \"247bae144385e9d64bf177e04652f48054303d20\", \"blink_fuzzer_unittests\": \"709bb60cc1341157ce2c431d2c230fbb009687f4\", \"blink_heap_unittests\": \"4c71b6a34572a3803f90fb0166f41e338c21555e\", \"blink_platform_unittests\": \"0bb3c191a13b9c9124499d74bc7cae8d188254ab\", \"blink_unittests\": \"dc47525e84342a744d1c1e0e69403663f8b992a1\", \"boringssl_crypto_tests\": \"edf2e10614e4d7fbabb6e2b4e56a2630204c35a1\", \"boringssl_ssl_tests\": \"f061c32389acaf2905500266add5eb654ecd8ea7\", \"browser_switcher_bho_unittests\": \"88ab1df89fcd45ad1435f45abbc651a56ffdf7a8\", \"browser_tests\": \"69979170e13813df2d49b4307ea3ab052fb8a4d9\", \"capture_unittests\": \"960945e045b28bfe4e12e8490009d89532121e98\", \"cast_unittests\": \"f38cf0d0a7734be67d73c931f6e4d015366a885a\", \"cc_unittests\": \"57d0a466231400eede80d7999436a1cde32a53c6\", \"chrome_app_unittests\": \"32d5bf53cfbcdf628f55abdeb6e669d3c4e1fcb5\", \"chrome_cleaner_unittests\": \"ff0aa9fa7c0d8f7092213473c7089f8f4c8088e6\", \"chrome_elf_unittests\": \"7f0a2f9dce8a02a8c3cba0a2e2b2e61b026d184d\", \"chromedriver_unittests\": \"4486196fb452081450318fd580a0d484bc61146a\", \"components_browsertests\": \"cfd5cc590e58436e9d8b11277f6ef380767bdc04\", \"components_unittests\": \"46d4f322de46d59d37ec362556e17164cbaac530\", \"compositor_unittests\": \"c569aefc14e7b79a4270badfeac52d34dca81d2a\", \"content_browsertests\": \"ea3351fe08b22030e0faed94726b334961cb6900\", \"content_unittests\": \"17396cf431b4f9242c0caf2845154fdfbafd0be1\", \"courgette_unittests\": \"9560636e129ef54e040b686330bc90577209603c\", \"crashpad_tests\": \"b66e6e1b90acab1eba13d89e7e792d7d638931f1\", \"cronet_tests\": \"9641c5f2e780ea71250ecdc98d95c220c202a5a5\", \"cronet_unittests\": \"7adb1876b5681a4d22caef8a43f2baddde4adc11\", \"crypto_unittests\": \"be3d8d8ae68e229a11699aa2ce6ffff67d5a2891\", \"delayloads_unittests\": \"bc71ef324688f8f0c3b5adafe3708b0c59259db9\", \"device_unittests\": \"a9f3f53c48e11d6a7787faebe6b2fffcf3d61914\", \"display_unittests\": \"0436cd89b5165803f91ddbb326699f5607fb8e95\", \"elevation_service_unittests\": \"255bc31cc5aa4bd94fdddfb8b432c9702e1fb713\", \"events_unittests\": \"48836c89168f45145901eb4d6849ae1bdfd485ad\", \"extensions_browsertests\": \"9149aa21b1d31574f73f2e8b80d3d2df005a9998\", \"extensions_unittests\": \"a41382222317180783c6b255024432287b56df50\", \"filesystem_service_unittests\": \"180fea9c2ab961e538acb24dd742fcb637dc521f\", \"gcm_unit_tests\": \"a58ce13a813ca90ebdffb76a10ec4c90958afd52\", \"gcp_unittests\": \"9b2fba1b1472085dd739df5c4708b3ebebe8812f\", \"gfx_unittests\": \"2b1557ba0f45f9807f48e6702433e4a4eaf1d342\", \"gin_unittests\": \"c8a8d34983fe69b2ee6b73dfee03403e5a34a2f5\", \"google_apis_unittests\": \"42d316f79c93e7399b98ad80c68779fa63052b0f\", \"gpu_unittests\": \"50fcdff65a2fe46fddcb0241acbb1f8e22a38044\", \"gwp_asan_unittests\": \"274019a9a50d17638738f5f56172318033a1766e\", \"headless_browsertests\": \"69f2579b072d3089f878c04192ad1d396fe1e214\", \"headless_unittests\": \"1e10006c0224bdae9c4a4627d03e5379205879a0\", \"install_static_unittests\": \"de4f61419988d00ee482297f926de2016af7cac1\", \"installer_util_unittests\": \"fb65f7c2d12553bda75be382bc859809640a6f20\", \"interactive_ui_tests\": \"e2d5a9f48c1c7322e94d085db8d03f6ea9ee6b47\", \"ipc_tests\": \"19c57755c8afe6f27ffa55472e9c8cd5169964fd\", \"jingle_unittests\": \"8720f362340ecfe337deebbf11a0be0e000676e6\", \"latency_unittests\": \"43ee2b795d9ce604912688e0da68f26891d8cb36\", \"libjingle_xmpp_unittests\": \"fb81887492bc2124d09936f957193c29d24a0e47\", \"liburlpattern_unittests\": \"8a6519df76d7b835e16a0bb23c4b6aecb08fdf2c\", \"media_blink_unittests\": \"6402897fc0f05af04e4f6816a744b89029ef88e6\", \"media_unittests\": \"59aa0f20c10e32ba570a36dbcd3a533db0f28254\", \"message_center_unittests\": \"31ca6a9f84b5d3cd850643f2ccdb2087121f7968\", \"midi_unittests\": \"a7aba47ee99e6c0800c6366d74415a79de889132\", \"mojo_core_unittests\": \"e981b3f2ed86ee1e0a459978c20571a19920bd68\", \"mojo_unittests\": \"6d7e1139e934d0fad9f4e9d0b7590f9e1ad326d3\", \"nacl_loader_unittests\": \"10c717a10b76afa237e2eda200777e12fbec0854\", \"native_theme_unittests\": \"0fad6c78ff82e28ac46a413f1a521d9cf36c44e8\", \"net_unittests\": \"a953ccb7254aa97952ca7a1b8d6d8bb9e743b71e\", \"notification_helper_unittests\": \"af6d35e20bc6e6398ccbee72d6d1ecd8d70a3187\", \"pdf_unittests\": \"36f891c2cd835ed06b510a994820381914207338\", \"perfetto_unittests\": \"8625b7542f05d72eaf2638b55a5d8e03b9758226\", \"ppapi_unittests\": \"4b66d726bc6a4933db84f0957955ee03c1580565\", \"printing_unittests\": \"58949b5aff5c132e862a9da16c91c7a39d64b486\", \"remoting_unittests\": \"bfb41a753fbdcfb9d7276ec159e38546c846e0b9\", \"sbox_integration_tests\": \"21ee6a037cb7da1ff0feeb741ff94595c613d372\", \"sbox_unittests\": \"0a42b578c4fa4815e44f943630a5b76b94a53141\", \"sbox_validation_tests\": \"03106d5ea08dfff43ceab6bd769ae52e28ac65a9\", \"service_manager_unittests\": \"85222a1a442254e8a90bfffb0f9657e10c938427\", \"services_unittests\": \"d16ded6074e5688100bc7b2169dac7bb0f1a42c6\", \"setup_unittests\": \"309e629a3621080f931ed5e8b8ec081e5d770157\", \"shell_dialogs_unittests\": \"70b0c806b01082b1e130671dd99126b5fae4ff50\", \"skia_unittests\": \"6b292b4afa1e9cd32dd37a34e32d300ff8828dc3\", \"snapshot_unittests\": \"3bad1fbe4317861555f979af35945ae60c52d6a3\", \"sql_unittests\": \"f681d2cd85d2e0543860113ad252300499a2a9ef\", \"storage_unittests\": \"1438a39e6a91514c956f67fa990066a9069878db\", \"sync_integration_tests\": \"e4b90c89f64eb5ead84174ec14658dbd5332c3fd\", \"traffic_annotation_auditor_unittests\": \"cf12724f76b0661d542c03cbb7b15d391c2d70aa\", \"ui_base_unittests\": \"c3c674b547249a96b22746d021d38c2daf17ef21\", \"ui_touch_selection_unittests\": \"aeb45b41d978365237ea7a0fcb3a857195f2a784\", \"unit_tests\": \"f52a90dea35e7e755580565bbdcd18293efe16a1\", \"updater_tests\": \"866995cd031a6353b5d1e69e564c8a123d75d614\", \"url_unittests\": \"e8f33a5551472cf3b8fd69209c5a77c316906e01\", \"views_unittests\": \"1db2abf4185ecfb6c3c76cf8b9d7593a069a0364\", \"viz_unittests\": \"557d93c122253ab9e274804cca9a9b116c1c7b4b\", \"vr_common_unittests\": \"e5cf49c254b24ee34bb6927245b76c2a19d88e4b\", \"vr_pixeltests\": \"d707a371833b4a86d7b7b885b41f1d8109d6c82b\", \"weblayer_browsertests\": \"6a7ded8333390785f5274546c8d9dd34c80b7440\", \"weblayer_unittests\": \"b6031fd6c86432d4ddbb0e29f01688288ba67685\", \"wm_unittests\": \"06ed210dbc22d705bb0b16a80e10cd25f5c72fad\", \"wtf_unittests\": \"78b348882d1cbbd27223d78edf1f0c25409688e4\", \"zlib_unittests\": \"9345de151e6e1e5571f055e4fb7eec6965f80741\", \"zucchini_unittests\": \"d571109f4423f275add2933062286100dfb7b720\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-8-uuz6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612261104692051",
"status": "COMPLETED",
"status_changed_ts": "1612264908240524",
"tags": [
"build_address:luci.chromium.ci/win-asan/17128",
"builder:win-asan",
"buildset:commit/git/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c9715bf00c03a07e71810a6bddc0d04c86bbadd6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021845482342505856",
"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/8856415420304047568/+/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:517b119aa5265710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612264908322400",
"url": "https://ci.chromium.org/b/8856415420304047568",
"utcnow_ts": "1612264909459550"
}
}
[11:21:49.468] Invocation finished in 1h3m30.355515652s with status SUCCEEDED