[04:55:00.151] New invocation is queued and will start shortly
[04:55:01.721] Starting the invocation (attempt 1)
[04:55:01.800] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:55:01.800] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:55:01.800] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023496569042770416",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"332474c07594fc516652c9444d67e81524b50980\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023496569042770416",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/332474c07594fc516652c9444d67e81524b50980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/332474c07594fc516652c9444d67e81524b50980",
"gitiles_ref:refs/heads/master"
]
}
[04:55:02.992] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610686501999932",
"id": "8858066506438092496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"332474c07594fc516652c9444d67e81524b50980\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610686502835340",
"tags": [
"build_address:luci.chromium.ci/win-asan/16830",
"builder:win-asan",
"buildset:commit/git/332474c07594fc516652c9444d67e81524b50980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/332474c07594fc516652c9444d67e81524b50980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023496569042770416",
"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/8858066506438092496/+/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": "1610686502835516",
"url": "https://ci.chromium.org/b/8858066506438092496",
"utcnow_ts": "1610686502972454"
}
}
[04:55:02.992] Task URL: https://ci.chromium.org/b/8858066506438092496
[04:55:02.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:2:0) after 1m0s
[04:55:06.743] Received PubSub notification, asking Buildbucket for the build status
[04:55:06.818] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:2:0)
[04:56:03.024] Timer tick, asking Buildbucket for the build status
[04:56:03.138] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:4:0) after 1m0s
[04:57:03.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:4:0)
[04:57:03.160] Timer tick, asking Buildbucket for the build status
[04:57:03.466] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:03.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:5:0) after 1m0s
[04:58:03.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:5:0)
[04:58:03.490] Timer tick, asking Buildbucket for the build status
[04:58:03.712] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:03.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:6:0) after 1m0s
[04:59:03.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:6:0)
[04:59:03.737] Timer tick, asking Buildbucket for the build status
[04:59:04.708] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:04.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:7:0) after 1m0s
[05:00:04.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:7:0)
[05:00:04.834] Timer tick, asking Buildbucket for the build status
[05:00:05.086] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:05.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:8:0) after 1m0s
[05:01:05.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:8:0)
[05:01:05.722] Timer tick, asking Buildbucket for the build status
[05:01:05.865] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:05.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:9:0) after 1m0s
[05:02:05.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:9:0)
[05:02:05.887] Timer tick, asking Buildbucket for the build status
[05:02:06.161] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:06.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:10:0) after 1m0s
[05:03:06.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:10:0)
[05:03:06.180] Timer tick, asking Buildbucket for the build status
[05:03:06.325] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:06.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:11:0) after 1m0s
[05:04:06.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:11:0)
[05:04:06.367] Timer tick, asking Buildbucket for the build status
[05:04:07.494] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:12:0) after 1m0s
[05:05:07.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:12:0)
[05:05:07.531] Timer tick, asking Buildbucket for the build status
[05:05:07.651] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:07.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:13:0) after 1m0s
[05:06:07.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:13:0)
[05:06:07.775] Timer tick, asking Buildbucket for the build status
[05:06:07.943] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:07.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:14:0) after 1m0s
[05:07:07.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:14:0)
[05:07:07.997] Timer tick, asking Buildbucket for the build status
[05:07:08.069] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:08.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:15:0) after 1m0s
[05:08:08.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:15:0)
[05:08:08.061] Timer tick, asking Buildbucket for the build status
[05:08:08.126] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:08.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:16:0) after 1m0s
[05:09:08.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:16:0)
[05:09:08.143] Timer tick, asking Buildbucket for the build status
[05:09:08.199] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:08.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:17:0) after 1m0s
[05:10:08.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:17:0)
[05:10:08.268] Timer tick, asking Buildbucket for the build status
[05:10:08.616] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:08.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:18:0) after 1m0s
[05:11:08.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:18:0)
[05:11:08.640] Timer tick, asking Buildbucket for the build status
[05:11:08.853] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:08.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:19:0) after 1m0s
[05:12:08.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:19:0)
[05:12:08.927] Timer tick, asking Buildbucket for the build status
[05:12:09.016] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:09.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:20:0) after 1m0s
[05:13:09.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:20:0)
[05:13:09.070] Timer tick, asking Buildbucket for the build status
[05:13:09.130] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:21:0) after 1m0s
[05:14:09.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:21:0)
[05:14:09.141] Timer tick, asking Buildbucket for the build status
[05:14:09.264] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:22:0) after 1m0s
[05:15:09.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:22:0)
[05:15:09.334] Timer tick, asking Buildbucket for the build status
[05:15:09.485] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:23:0) after 1m0s
[05:16:09.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:23:0)
[05:16:09.485] Timer tick, asking Buildbucket for the build status
[05:16:09.562] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:24:0) after 1m0s
[05:17:09.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:24:0)
[05:17:09.697] Timer tick, asking Buildbucket for the build status
[05:17:09.766] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:09.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:25:0) after 1m0s
[05:18:09.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:25:0)
[05:18:09.915] Timer tick, asking Buildbucket for the build status
[05:18:10.055] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:10.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:26:0) after 1m0s
[05:19:10.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:26:0)
[05:19:10.157] Timer tick, asking Buildbucket for the build status
[05:19:10.552] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:27:0) after 1m0s
[05:20:10.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:27:0)
[05:20:10.614] Timer tick, asking Buildbucket for the build status
[05:20:11.032] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:28:0) after 1m0s
[05:21:11.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:28:0)
[05:21:11.104] Timer tick, asking Buildbucket for the build status
[05:21:11.238] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:29:0) after 1m0s
[05:22:11.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:29:0)
[05:22:11.266] Timer tick, asking Buildbucket for the build status
[05:22:11.408] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:11.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:30:0) after 1m0s
[05:23:11.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:30:0)
[05:23:11.432] Timer tick, asking Buildbucket for the build status
[05:23:11.572] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:31:0) after 1m0s
[05:24:11.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:31:0)
[05:24:11.623] Timer tick, asking Buildbucket for the build status
[05:24:11.751] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:11.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:32:0) after 1m0s
[05:25:11.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:32:0)
[05:25:11.804] Timer tick, asking Buildbucket for the build status
[05:25:11.953] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:11.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:33:0) after 1m0s
[05:26:11.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:33:0)
[05:26:11.986] Timer tick, asking Buildbucket for the build status
[05:26:12.131] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:12.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:34:0) after 1m0s
[05:27:12.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:34:0)
[05:27:12.177] Timer tick, asking Buildbucket for the build status
[05:27:12.334] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:12.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:35:0) after 1m0s
[05:28:12.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:35:0)
[05:28:12.366] Timer tick, asking Buildbucket for the build status
[05:28:12.501] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:12.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:36:0) after 1m0s
[05:29:12.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:36:0)
[05:29:12.561] Timer tick, asking Buildbucket for the build status
[05:29:12.630] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:12.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:37:0) after 1m0s
[05:30:12.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:37:0)
[05:30:12.712] Timer tick, asking Buildbucket for the build status
[05:30:12.854] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:12.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:38:0) after 1m0s
[05:31:12.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:38:0)
[05:31:12.973] Timer tick, asking Buildbucket for the build status
[05:31:13.045] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:13.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:39:0) after 1m0s
[05:32:13.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:39:0)
[05:32:13.146] Timer tick, asking Buildbucket for the build status
[05:32:13.249] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:13.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:40:0) after 1m0s
[05:33:13.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:40:0)
[05:33:13.302] Timer tick, asking Buildbucket for the build status
[05:33:13.746] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:13.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:41:0) after 1m0s
[05:34:13.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:41:0)
[05:34:13.770] Timer tick, asking Buildbucket for the build status
[05:34:13.878] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:13.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:42:0) after 1m0s
[05:35:13.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:42:0)
[05:35:13.924] Timer tick, asking Buildbucket for the build status
[05:35:14.080] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:14.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:43:0) after 1m0s
[05:36:14.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:43:0)
[05:36:14.130] Timer tick, asking Buildbucket for the build status
[05:36:14.433] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:14.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:44:0) after 1m0s
[05:37:14.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:44:0)
[05:37:14.516] Timer tick, asking Buildbucket for the build status
[05:37:14.593] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:14.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:45:0) after 1m0s
[05:38:14.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:45:0)
[05:38:14.634] Timer tick, asking Buildbucket for the build status
[05:38:14.736] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:14.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:46:0) after 1m0s
[05:39:14.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:46:0)
[05:39:14.793] Timer tick, asking Buildbucket for the build status
[05:39:14.915] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:14.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:47:0) after 1m0s
[05:40:14.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:47:0)
[05:40:14.988] Timer tick, asking Buildbucket for the build status
[05:40:15.197] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:15.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:48:0) after 1m0s
[05:41:15.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:48:0)
[05:41:15.269] Timer tick, asking Buildbucket for the build status
[05:41:15.350] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:15.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:49:0) after 1m0s
[05:42:15.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:49:0)
[05:42:15.371] Timer tick, asking Buildbucket for the build status
[05:42:15.468] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:15.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:50:0) after 1m0s
[05:43:15.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:50:0)
[05:43:15.535] Timer tick, asking Buildbucket for the build status
[05:43:15.648] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:15.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:51:0) after 1m0s
[05:44:15.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:51:0)
[05:44:15.699] Timer tick, asking Buildbucket for the build status
[05:44:16.067] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:16.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:52:0) after 1m0s
[05:45:16.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:52:0)
[05:45:16.086] Timer tick, asking Buildbucket for the build status
[05:45:16.217] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:16.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:53:0) after 1m0s
[05:46:16.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:53:0)
[05:46:16.276] Timer tick, asking Buildbucket for the build status
[05:46:16.344] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:16.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:54:0) after 1m0s
[05:47:16.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:54:0)
[05:47:16.370] Timer tick, asking Buildbucket for the build status
[05:47:16.492] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:55:0) after 1m0s
[05:48:16.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:55:0)
[05:48:16.564] Timer tick, asking Buildbucket for the build status
[05:48:16.779] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:16.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:56:0) after 1m0s
[05:49:16.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:56:0)
[05:49:16.816] Timer tick, asking Buildbucket for the build status
[05:49:16.981] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:16.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:57:0) after 1m0s
[05:50:17.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:57:0)
[05:50:17.012] Timer tick, asking Buildbucket for the build status
[05:50:17.222] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:17.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:58:0) after 1m0s
[05:51:17.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:58:0)
[05:51:17.287] Timer tick, asking Buildbucket for the build status
[05:51:17.439] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:17.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:59:0) after 1m0s
[05:52:17.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:59:0)
[05:52:17.527] Timer tick, asking Buildbucket for the build status
[05:52:17.602] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:17.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:60:0) after 1m0s
[05:53:17.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:60:0)
[05:53:17.650] Timer tick, asking Buildbucket for the build status
[05:53:17.718] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:17.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:61:0) after 1m0s
[05:54:17.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:61:0)
[05:54:17.864] Timer tick, asking Buildbucket for the build status
[05:54:17.953] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:17.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:62:0) after 1m0s
[05:55:17.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:62:0)
[05:55:17.996] Timer tick, asking Buildbucket for the build status
[05:55:18.379] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:18.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:63:0) after 1m0s
[05:56:18.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:63:0)
[05:56:18.403] Timer tick, asking Buildbucket for the build status
[05:56:18.816] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:18.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:64:0) after 1m0s
[05:57:18.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:64:0)
[05:57:18.847] Timer tick, asking Buildbucket for the build status
[05:57:18.979] Build 8858066506438092496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:18.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023496569042770416:65:0) after 1m0s
[05:57:57.239] Received PubSub notification, asking Buildbucket for the build status
[05:57:57.389] Build 8858066506438092496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:57:57.389] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610690276243894",
"created_by": "project:chromium",
"created_ts": "1610686501999932",
"id": "8858066506438092496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"332474c07594fc516652c9444d67e81524b50980\"}}",
"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\": \"332474c07594fc516652c9444d67e81524b50980\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16830, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"50c82f42e33fe7b58b7fd8ad343d0c7c8245eedb\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"332474c07594fc516652c9444d67e81524b50980\", \"got_revision_cp\": \"refs/heads/master@{#843949}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"332474c07594fc516652c9444d67e81524b50980\", \"swarm_hashes_refs/heads/master(at){#843949}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"1d314fc6efba1997dd3f7ba25abef067b0b5c4b3\", \"angle_unittests\": \"817566b5f3228621d1638dcb81fa3d6915556fd8\", \"app_shell_unittests\": \"fe7c3a5ac10a79e10a9c5c73d239809e25e53f78\", \"aura_unittests\": \"c9ce5a53836513a09173c8ce255d4542e4fa83d6\", \"base_unittests\": \"8174246b5bfa345a5bf3ac78ce8a496740fb252f\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"3ae543ace1ed95f238d4bd8201dde96f93e4fb87\", \"blink_fuzzer_unittests\": \"a3245b58f6be20d325a9df5aceb299e92fbc2723\", \"blink_heap_unittests\": \"6ddc69e35d44b239707af54079a3016c1811a484\", \"blink_platform_unittests\": \"1496ae1654c9556458cb48bd56c656dc00cf4714\", \"blink_unittests\": \"82916e193c60fba059e84a88cd8d8f8712c6fec3\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"acc705c442a345baec4b4f3f6db53dd56b4d8c80\", \"capture_unittests\": \"da6e218ac18f1716d1d6feb08bbb8f0dab7c9ce7\", \"cast_unittests\": \"ce5deaff9db75921be3b4208265e7ee88bb5a2b4\", \"cc_unittests\": \"ef3452b1a0b9278f73a0388a10ef8d549b6990ae\", \"chrome_app_unittests\": \"1eb29d57eb805553d8816a20fe3877c8e92ce7f6\", \"chrome_cleaner_unittests\": \"c75b3c485080d9c102fd4b7651a80de43afe4f13\", \"chrome_elf_unittests\": \"3abe66bb33c5abae7906009819fdc8ea9574246e\", \"chromedriver_unittests\": \"5fc1f08eb48e01f51e075064306c2882a54e4c46\", \"components_browsertests\": \"92f184c7ffb10687041107a9feb20e154722d6a8\", \"components_unittests\": \"c0b74c7ad24140c33568a24318bcc876eb2446e7\", \"compositor_unittests\": \"93073ceb43191f289194c33b6dd0dd4b7fc68114\", \"content_browsertests\": \"263e69c79bc96055f77de83ea2f6e09495a26903\", \"content_unittests\": \"ccb8844fef832e47fffd99756059404011b9dbe0\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"6edd595ed3da1563ff92708a4bc09aef633f85d5\", \"cronet_unittests\": \"0979f8c20e217f4154498e353dfdffb746d4676d\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"70e2126f2f7add00b9cea5c374f0d6c836713388\", \"device_unittests\": \"9f4391c5363ac1b4eeef5e857b38a9f255c1fb47\", \"display_unittests\": \"9c722805aed7d61e93a26f68502fbdbe8199b455\", \"elevation_service_unittests\": \"3ff9e2db64fa89d8fc7466956152e33975c0a4a8\", \"events_unittests\": \"90562bfe7b45abb03282de92e4a4366e5415af67\", \"extensions_browsertests\": \"b6dd6c937648bc4a7b3352377eddcaa395eb41bf\", \"extensions_unittests\": \"a3700f2433cede656c943e9514997ece9695f48c\", \"filesystem_service_unittests\": \"1a0a90ec0e5e56ae3e1106d44d642ed86eb571b7\", \"gcm_unit_tests\": \"285c89def9ea88b9f6ee07233f31a93ba9f2f372\", \"gcp_unittests\": \"80fb19fd4a7fce63b466001469fd68fca12cfa7e\", \"gfx_unittests\": \"2d083b85f07a2d71de649de42aa8784ea23ebd21\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"64c08dfd14d303371f34692d2e24838649f3ecfe\", \"gpu_unittests\": \"2f5f9540a2c8e512444b0d4fb3c53035ffe278a7\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"851ddef7fc07852d7c939a2a9005892169c9cb06\", \"headless_unittests\": \"ecedaa7f47aabdab6fd15609ed6a1fadabe646ae\", \"install_static_unittests\": \"d49b57e36d9f580b2207b3a09bab7dc64c1c508a\", \"installer_util_unittests\": \"5d3abf9d80138a13a07120c9925a7554420785c3\", \"interactive_ui_tests\": \"ccb22ea93678a9c135ead55e3109d5ad4b3db43e\", \"ipc_tests\": \"04b29ea2dcac58398dc589b81059bb615997fce1\", \"jingle_unittests\": \"65a1cc02c214c4e42ce76694360b7d7dfe587142\", \"latency_unittests\": \"a52d62052f352647292a56f64c43126e68a233f8\", \"libjingle_xmpp_unittests\": \"195c09bcc503e019af953742f15d87e622df0ee4\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"5424e47c10475af8dbb59b2268969d7a874580de\", \"media_unittests\": \"9c163cd6e45c7b64176ca4a30562a4052568c3d8\", \"message_center_unittests\": \"cc5c83cc25213ebba6926c81dda077a682ee189e\", \"midi_unittests\": \"63ef9c56554fdcd74f5124fb04fc6dcc32676df1\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"b4547587e97d8de2a396e847bc35eafbbacae1d0\", \"nacl_loader_unittests\": \"a4e60674862900a5291bc0d53cda5189899cc6a0\", \"native_theme_unittests\": \"8fa744daed3b6d49bbfc8be6e5464283535de457\", \"net_unittests\": \"a399dd2d37a063a87dc1fb0ed51cdc7c8914d67b\", \"notification_helper_unittests\": \"e3e443038223a6bab928870181a88cdc3ba41929\", \"pdf_unittests\": \"ccee8cbdfd12d8eaa2615c340df84562490dd950\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"4568f0463abc8a59ff24b0a44a931625dd93c923\", \"printing_unittests\": \"4ad26bcd2dc9f952b89f9ca02627e54d07645853\", \"remoting_unittests\": \"e04c6d978e06fdcf97b95b3ef57c930023f54b89\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"fb503dfe95a0cc5d042f7a5a3bb4a2045e990460\", \"services_unittests\": \"e78581f82519c09e3e6c99358282f9efc0b313d9\", \"setup_unittests\": \"b538b35b98c37a26337f606ee3458d36ae5aec7b\", \"shell_dialogs_unittests\": \"4a6ac3779b57d39d21ffbe1264ad5e7c213ac3f8\", \"skia_unittests\": \"819b1ea362c95a27279001f2bee92b9bd1eb2aee\", \"snapshot_unittests\": \"200f780a86356c365e49acebfe42ae7517336a68\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"0e69a78e5e21f34e2d9940b4f3c5edd40c41021d\", \"sync_integration_tests\": \"542d396a907f2b8f955b0111df4e00c48ce4b378\", \"traffic_annotation_auditor_unittests\": \"5ca5b0dfb6e577335278e6a58d35ac57539b9360\", \"ui_base_unittests\": \"ef7f062d0d47e49d63dcf39d534bdbeca0de3d03\", \"ui_touch_selection_unittests\": \"cbb9971b04b5e2437e91ae231b837d1ef5b5b71a\", \"unit_tests\": \"2d99558884f09a75c0e2c8050817baff2bb4ad36\", \"updater_tests\": \"f6ed2cb46ceab6f333b765c3be3828a684e23ee8\", \"url_unittests\": \"ca5c3c0079d3547037355463520ce302894f4548\", \"views_unittests\": \"af5da883a2ab2e73d442519f3ce848de5a2a3051\", \"viz_unittests\": \"804076e1d5c1c86d932a0e3d4be8ee601de78932\", \"vr_common_unittests\": \"469b4552ff5ccb0111eedee721d3cbcbaf1d333f\", \"vr_pixeltests\": \"358c6479f38ab268da1f77b61d26fd21908cad28\", \"weblayer_browsertests\": \"d8bd4d3079bfd7f1f7e03208eba4cb5b870397b1\", \"weblayer_unittests\": \"5c76bbf7b49af81078ebf1268f365febc5866e12\", \"wm_unittests\": \"fb5d102439cf07ae2652541164af46bff15f44e9\", \"wtf_unittests\": \"5997b7a440375abd4db13d55db00a528c1d0423e\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-3-nb2x\"], \"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\": [\"5666-679c9bd\"], \"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": "1610686505872973",
"status": "COMPLETED",
"status_changed_ts": "1610690276479095",
"tags": [
"build_address:luci.chromium.ci/win-asan/16830",
"builder:win-asan",
"buildset:commit/git/332474c07594fc516652c9444d67e81524b50980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/332474c07594fc516652c9444d67e81524b50980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023496569042770416",
"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/8858066506438092496/+/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:511d37235e58d610",
"user_agent:luci-scheduler"
],
"updated_ts": "1610690276494941",
"url": "https://ci.chromium.org/b/8858066506438092496",
"utcnow_ts": "1610690277376918"
}
}
[05:57:57.389] Invocation finished in 1h2m57.258282028s with status SUCCEEDED