[07:51:17.237] New invocation is queued and will start shortly
[07:51:18.507] Starting the invocation (attempt 1)
[07:51:18.546] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:51:18.548] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:51:18.548] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030733235475293792",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030733235475293792",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"gitiles_ref:refs/heads/master"
]
}
[07:51:19.326] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603785078645379",
"id": "8865303173332991200",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603785079139999",
"tags": [
"build_address:luci.chromium.ci/win-asan/15300",
"builder:win-asan",
"buildset:commit/git/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030733235475293792",
"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/8865303173332991200/+/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": "1603785079140121",
"url": "https://ci.chromium.org/b/8865303173332991200",
"utcnow_ts": "1603785079270335"
}
}
[07:51:19.326] Task URL: https://ci.chromium.org/b/8865303173332991200
[07:51:19.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:2:0) after 1m0s
[07:51:22.991] Received PubSub notification, asking Buildbucket for the build status
[07:51:23.083] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:19.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:2:0)
[07:52:19.345] Timer tick, asking Buildbucket for the build status
[07:52:19.696] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:19.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:4:0) after 1m0s
[07:53:19.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:4:0)
[07:53:19.736] Timer tick, asking Buildbucket for the build status
[07:53:19.876] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:19.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:5:0) after 1m0s
[07:54:19.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:5:0)
[07:54:19.938] Timer tick, asking Buildbucket for the build status
[07:54:20.042] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:20.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:6:0) after 1m0s
[07:55:20.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:6:0)
[07:55:20.051] Timer tick, asking Buildbucket for the build status
[07:55:20.168] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:20.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:7:0) after 1m0s
[07:56:20.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:7:0)
[07:56:20.219] Timer tick, asking Buildbucket for the build status
[07:56:20.380] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:8:0) after 1m0s
[07:57:20.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:8:0)
[07:57:20.399] Timer tick, asking Buildbucket for the build status
[07:57:20.464] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:20.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:9:0) after 1m0s
[07:58:20.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:9:0)
[07:58:20.502] Timer tick, asking Buildbucket for the build status
[07:58:20.585] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:10:0) after 1m0s
[07:59:20.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:10:0)
[07:59:20.605] Timer tick, asking Buildbucket for the build status
[07:59:20.713] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:11:0) after 1m0s
[08:00:20.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:11:0)
[08:00:20.751] Timer tick, asking Buildbucket for the build status
[08:00:20.902] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:12:0) after 1m0s
[08:01:21.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:12:0)
[08:01:21.014] Timer tick, asking Buildbucket for the build status
[08:01:21.334] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:13:0) after 1m0s
[08:02:21.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:13:0)
[08:02:21.494] Timer tick, asking Buildbucket for the build status
[08:02:21.612] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:21.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:14:0) after 1m0s
[08:03:21.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:14:0)
[08:03:21.681] Timer tick, asking Buildbucket for the build status
[08:03:22.047] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:15:0) after 1m0s
[08:04:22.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:15:0)
[08:04:22.144] Timer tick, asking Buildbucket for the build status
[08:04:22.214] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:16:0) after 1m0s
[08:05:22.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:16:0)
[08:05:22.373] Timer tick, asking Buildbucket for the build status
[08:05:23.336] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:23.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:17:0) after 1m0s
[08:06:23.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:17:0)
[08:06:23.357] Timer tick, asking Buildbucket for the build status
[08:06:23.722] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:18:0) after 1m0s
[08:07:23.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:18:0)
[08:07:23.771] Timer tick, asking Buildbucket for the build status
[08:07:23.842] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:23.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:19:0) after 1m0s
[08:08:24.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:19:0)
[08:08:24.088] Timer tick, asking Buildbucket for the build status
[08:08:24.482] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:24.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:20:0) after 1m0s
[08:09:24.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:20:0)
[08:09:24.644] Timer tick, asking Buildbucket for the build status
[08:09:24.708] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:24.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:21:0) after 1m0s
[08:10:24.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:21:0)
[08:10:24.734] Timer tick, asking Buildbucket for the build status
[08:10:24.851] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:24.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:22:0) after 1m0s
[08:11:24.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:22:0)
[08:11:24.912] Timer tick, asking Buildbucket for the build status
[08:11:25.090] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:25.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:23:0) after 1m0s
[08:12:25.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:23:0)
[08:12:25.189] Timer tick, asking Buildbucket for the build status
[08:12:25.257] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:25.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:24:0) after 1m0s
[08:13:25.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:24:0)
[08:13:25.309] Timer tick, asking Buildbucket for the build status
[08:13:25.376] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:25.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:25:0) after 1m0s
[08:14:25.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:25:0)
[08:14:25.430] Timer tick, asking Buildbucket for the build status
[08:14:25.582] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:25.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:26:0) after 1m0s
[08:15:25.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:26:0)
[08:15:25.649] Timer tick, asking Buildbucket for the build status
[08:15:25.807] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:25.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:27:0) after 1m0s
[08:16:25.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:27:0)
[08:16:25.865] Timer tick, asking Buildbucket for the build status
[08:16:26.043] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:26.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:28:0) after 1m0s
[08:17:26.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:28:0)
[08:17:26.020] Timer tick, asking Buildbucket for the build status
[08:17:26.206] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:26.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:29:0) after 1m0s
[08:18:26.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:29:0)
[08:18:26.262] Timer tick, asking Buildbucket for the build status
[08:18:26.498] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:26.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:30:0) after 1m0s
[08:19:26.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:30:0)
[08:19:26.625] Timer tick, asking Buildbucket for the build status
[08:19:26.983] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:26.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:31:0) after 1m0s
[08:20:27.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:31:0)
[08:20:27.004] Timer tick, asking Buildbucket for the build status
[08:20:27.184] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:27.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:32:0) after 1m0s
[08:21:27.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:32:0)
[08:21:27.232] Timer tick, asking Buildbucket for the build status
[08:21:27.411] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:27.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:33:0) after 1m0s
[08:22:27.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:33:0)
[08:22:27.481] Timer tick, asking Buildbucket for the build status
[08:22:27.857] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:34:0) after 1m0s
[08:23:27.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:34:0)
[08:23:27.886] Timer tick, asking Buildbucket for the build status
[08:23:28.059] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:28.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:35:0) after 1m0s
[08:24:28.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:35:0)
[08:24:28.129] Timer tick, asking Buildbucket for the build status
[08:24:28.252] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:28.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:36:0) after 1m0s
[08:25:28.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:36:0)
[08:25:28.277] Timer tick, asking Buildbucket for the build status
[08:25:28.497] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:28.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:37:0) after 1m0s
[08:26:28.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:37:0)
[08:26:28.538] Timer tick, asking Buildbucket for the build status
[08:26:28.688] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:28.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:38:0) after 1m0s
[08:27:28.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:38:0)
[08:27:28.725] Timer tick, asking Buildbucket for the build status
[08:27:28.819] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:28.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:39:0) after 1m0s
[08:28:28.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:39:0)
[08:28:28.843] Timer tick, asking Buildbucket for the build status
[08:28:29.369] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:29.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:40:0) after 1m0s
[08:29:29.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:40:0)
[08:29:29.413] Timer tick, asking Buildbucket for the build status
[08:29:29.494] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:29.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:41:0) after 1m0s
[08:30:29.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:41:0)
[08:30:29.519] Timer tick, asking Buildbucket for the build status
[08:30:29.641] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:29.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:42:0) after 1m0s
[08:31:29.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:42:0)
[08:31:29.666] Timer tick, asking Buildbucket for the build status
[08:31:29.798] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:29.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:43:0) after 1m0s
[08:32:29.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:43:0)
[08:32:29.850] Timer tick, asking Buildbucket for the build status
[08:32:29.938] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:29.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:44:0) after 1m0s
[08:33:29.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:44:0)
[08:33:29.985] Timer tick, asking Buildbucket for the build status
[08:33:30.217] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:30.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:45:0) after 1m0s
[08:34:30.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:45:0)
[08:34:30.283] Timer tick, asking Buildbucket for the build status
[08:34:30.416] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:30.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:46:0) after 1m0s
[08:35:30.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:46:0)
[08:35:30.482] Timer tick, asking Buildbucket for the build status
[08:35:30.563] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:30.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:47:0) after 1m0s
[08:36:30.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:47:0)
[08:36:30.653] Timer tick, asking Buildbucket for the build status
[08:36:30.769] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:30.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:48:0) after 1m0s
[08:37:30.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:48:0)
[08:37:30.792] Timer tick, asking Buildbucket for the build status
[08:37:30.922] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:30.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:49:0) after 1m0s
[08:38:30.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:49:0)
[08:38:30.991] Timer tick, asking Buildbucket for the build status
[08:38:31.073] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:31.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:50:0) after 1m0s
[08:39:31.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:50:0)
[08:39:31.173] Timer tick, asking Buildbucket for the build status
[08:39:31.874] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:31.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:51:0) after 1m0s
[08:40:32.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:51:0)
[08:40:32.009] Timer tick, asking Buildbucket for the build status
[08:40:32.163] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:32.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:52:0) after 1m0s
[08:41:32.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:52:0)
[08:41:32.229] Timer tick, asking Buildbucket for the build status
[08:41:32.664] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:32.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:53:0) after 1m0s
[08:42:32.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:53:0)
[08:42:32.731] Timer tick, asking Buildbucket for the build status
[08:42:33.116] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:33.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:54:0) after 1m0s
[08:43:33.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:54:0)
[08:43:33.205] Timer tick, asking Buildbucket for the build status
[08:43:33.370] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:33.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:55:0) after 1m0s
[08:44:33.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:55:0)
[08:44:33.436] Timer tick, asking Buildbucket for the build status
[08:44:33.749] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:33.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:56:0) after 1m0s
[08:45:33.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:56:0)
[08:45:33.839] Timer tick, asking Buildbucket for the build status
[08:45:33.946] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:33.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:57:0) after 1m0s
[08:46:34.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:57:0)
[08:46:34.001] Timer tick, asking Buildbucket for the build status
[08:46:34.261] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:34.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:58:0) after 1m0s
[08:47:34.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:58:0)
[08:47:34.316] Timer tick, asking Buildbucket for the build status
[08:47:34.390] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:34.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:59:0) after 1m0s
[08:48:34.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:59:0)
[08:48:34.478] Timer tick, asking Buildbucket for the build status
[08:48:34.573] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:34.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:60:0) after 1m0s
[08:49:34.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:60:0)
[08:49:34.659] Timer tick, asking Buildbucket for the build status
[08:49:34.786] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:34.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:61:0) after 1m0s
[08:50:34.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:61:0)
[08:50:34.875] Timer tick, asking Buildbucket for the build status
[08:50:35.478] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:35.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:62:0) after 1m0s
[08:51:35.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:62:0)
[08:51:35.568] Timer tick, asking Buildbucket for the build status
[08:51:35.761] Build 8865303173332991200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:35.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030733235475293792:63:0) after 1m0s
[08:52:19.684] Received PubSub notification, asking Buildbucket for the build status
[08:52:19.798] Build 8865303173332991200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:52:19.798] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603788738915227",
"created_by": "project:chromium",
"created_ts": "1603785078645379",
"id": "8865303173332991200",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\"}}",
"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\": \"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\", \"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-1-kqo6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15300, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"b5ba693316fdd5e680f2bb4cf1a2f9bed589d8e2\", \"got_dawn_revision\": \"41c24933da1e6c5ec111f824bfc5977be244d3e4\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\", \"got_revision_cp\": \"refs/heads/master@{#821124}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"e641c3d83999fdc41495556cebe96c7fbe114fc3\", \"got_v8_revision_cp\": \"refs/heads/8.8.179@{#1}\", \"got_webrtc_revision\": \"0fb0eb3e80c09e0483c4dccdaa472e7e81f182fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32491}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"98b751e83dba5fd24d2a95f28b3a6a9789ee3edd\", \"swarm_hashes_refs/heads/master(at){#821124}_without_patch\": {\"absl_hardening_tests\": \"9f7114a9c651eb0899d7ad43c82250e2eca84942\", \"accessibility_unittests\": \"6cf9db8c6326d41cc8addf6c8fecb7d5c8685a7f\", \"angle_unittests\": \"c387a5643d06b4b7b5a55c4d96252b0667a1d6a8\", \"app_shell_unittests\": \"29e8d8a6ef07a765fb55a132ddc714bfb91289de\", \"aura_unittests\": \"525fde497aa47b9e1164c64208dfd2cb49f27a41\", \"base_unittests\": \"6038cd39dca98e20ee5440e27b7988dae06c0157\", \"base_util_unittests\": \"08ab70b798a5afe77ebb566ce127dd9cad2f7845\", \"blink_common_unittests\": \"4da90acfb2720731fe4a112e231e47d1741a45af\", \"blink_fuzzer_unittests\": \"860ca716b20720857fc47a8027cb830947f23ca9\", \"blink_heap_unittests\": \"19d8ab2dcbd3ec14504901c8ed691cd51a899523\", \"blink_platform_unittests\": \"6c9ef6dbf28786dcbdd0bb089a993372661166b5\", \"blink_unittests\": \"43720a6b6d3c978c919f06e8e512ea963ea22e87\", \"boringssl_crypto_tests\": \"68051820af770b9085875d92dc90d4fbb14b940d\", \"boringssl_ssl_tests\": \"76fe24a0f4087e81d7d041de86b8dd1ea427ff5a\", \"browser_switcher_bho_unittests\": \"2e19a386d8b70ffbd04bfdb3a5b3a2ffbed447f9\", \"browser_tests\": \"86e56afd16f8938c78bdf111092548c6bfc43d5a\", \"capture_unittests\": \"761ceba2ed3b4656164ae815b9349ca13316ae59\", \"cast_unittests\": \"d67404b5a1a96dfa14d3632892150ed7714121da\", \"cc_unittests\": \"7b50018f382e96d0faed074392755bcb23fb1d47\", \"chrome_app_unittests\": \"c45fdf51bdcec908fb2780030a6950cf1bfea4f4\", \"chrome_cleaner_unittests\": \"618282b7864d360fc81fbae5c93f89765a73e1db\", \"chrome_elf_unittests\": \"3345a17787d1fac02a0fedd1113eada6046a493c\", \"chromedriver_unittests\": \"055feacb1d38851939b7bf2fa94f9c2334a184ce\", \"components_browsertests\": \"b9b1381afec7123b9a4c158fa6295122e27a76b0\", \"components_unittests\": \"8358142d2fc0b08917102af1a51f763e65261cdf\", \"compositor_unittests\": \"aad17fb1342b461889a26d4d14a6b9d488e19ab7\", \"content_browsertests\": \"0f47dcf539469b0465824e42c008089b633fd591\", \"content_unittests\": \"999cc9c5644e847475e64cf24f52406f6312bb4d\", \"courgette_unittests\": \"7dbc0d7236054b3e738e45266176dbd902acc9a5\", \"crashpad_tests\": \"978a4018e162ac5bb2210bf62c75b714072fee2c\", \"cronet_tests\": \"81ae5721e1374e521c689bb5c677f5bba9506d8b\", \"cronet_unittests\": \"d5ca089d48154910707e4fe9d69c5285b4fcef8c\", \"crypto_unittests\": \"ec7aebcd0ba85779480e0525df423ad6551513c6\", \"delayloads_unittests\": \"0fe728a7210eaf9d8fe09a8b71bd9ca51df9fab4\", \"device_unittests\": \"ba092bfccca11cb5bad75bc5d8d0cf595750192d\", \"display_unittests\": \"ac604c63162243c8c019f179dc9b52701da18686\", \"elevation_service_unittests\": \"0af1f707d80f8d2ffed22f859d70f85a2cdde053\", \"events_unittests\": \"01683acde359ffb06237b391dab0a98093938d3a\", \"extensions_browsertests\": \"8d357f260b7ffea1db27b80b984fa029d65949fe\", \"extensions_unittests\": \"a357fa0d10f6122238a1589ce5ab2608ce28a281\", \"filesystem_service_unittests\": \"167472bed19209f6f48629ee8d22065a8d305976\", \"gcm_unit_tests\": \"804c1aeb335ce0a1cd5f4a01690255d8fc17c8d0\", \"gcp_unittests\": \"3a4197c044b700aec4e0d29fe4628dfaa131bd5a\", \"gfx_unittests\": \"db7d466d37b79cd38e6788b72794d9f04c21a03d\", \"gin_unittests\": \"1e575171deffcc2665ce50269b64d0a3afb517ed\", \"google_apis_unittests\": \"ddc694f3ed46c0aa9af0281e0a462c55432e11a6\", \"gpu_unittests\": \"3a5ae919bf753f9722ed1f5efc6e46576b6780cc\", \"gwp_asan_unittests\": \"7377518809192f5b25152db47fc6508ba3049e4a\", \"headless_browsertests\": \"1d11deed0e5346b9fcd04a89cf0a0448b322e0c0\", \"headless_unittests\": \"a6a464883b8ce9e3c85e738e66b0763b74e4c3f2\", \"install_static_unittests\": \"f37523722719c5386e3478efc88d67ae075cecff\", \"installer_util_unittests\": \"94c8b9f8c708c42e225102a99f95526691d88107\", \"interactive_ui_tests\": \"607795ca3d9a8ef43f1371e2b8977d53978b9f79\", \"ipc_tests\": \"a8f419d5fa8f3a8a14c78b33b6a8ab5cb5a917c1\", \"jingle_unittests\": \"acdd2b32922bb9dea950a6036a8b239e4a23a049\", \"latency_unittests\": \"119158a01bffe5017e76cb9098ee4e9ca4922fbc\", \"libjingle_xmpp_unittests\": \"50a78b88c1925091f042528a48d391e574dc0284\", \"media_blink_unittests\": \"1f37d096048012996a7ada4400ad3aca64eb61bc\", \"media_unittests\": \"4e3901b516a79925c17fb6633a3ad3cb6f199d04\", \"message_center_unittests\": \"39bed617240278f079b34f8917a46115e18a099b\", \"midi_unittests\": \"628b806640e74f22e563483fd252484e837a6a1f\", \"mojo_core_unittests\": \"3613980035ce5921e407471161049da03a981008\", \"mojo_unittests\": \"81084b1f3f3633214c878119f8b311ca86807266\", \"nacl_loader_unittests\": \"31a1571e495249a910ca61a5bcea5af3b00a940c\", \"native_theme_unittests\": \"f1defd025d40037aa055f85a6601aed5175a1880\", \"net_unittests\": \"e41aa3cb89ca016f28730943be00cb707165d59f\", \"notification_helper_unittests\": \"3bf67844c034a19f4473b52ea5d1cb58a1935364\", \"pdf_unittests\": \"465ff2b32600da42e3be6febb81dc41d4ac2635e\", \"perfetto_unittests\": \"513e98d3c506595bf5640225ab68ebbb7f02839f\", \"ppapi_unittests\": \"f61986c3417c35276d33c7945635a621e5b1d98b\", \"printing_unittests\": \"984186a14fb5e985a7aac0c4caf971df5a96262c\", \"remoting_unittests\": \"ca9852de889ce881ca7e00b8b2418a597a2a7fcb\", \"sbox_integration_tests\": \"57d5a30241f7cf7ecfa6053ce4e015f325b802fb\", \"sbox_unittests\": \"26b15494d449c0865c6a20b0a49060b1e5ea4bbb\", \"sbox_validation_tests\": \"93f87532c8e0bebadde804e9a207212d56f4aa2d\", \"service_manager_unittests\": \"4e458bb4854b1d65159f7190b7af079f83dce9a9\", \"services_unittests\": \"1acd3c6f66cdf76eb21a972fd33a11f2c8279697\", \"setup_unittests\": \"02505acea41a45cc8b8f82e7cd17acdeed2ef55e\", \"shell_dialogs_unittests\": \"d58905713d75e769665e7b4477e43b4328499e8f\", \"skia_unittests\": \"99c5f03a9142d5df19e48678f8fbf3bd0aa5c020\", \"snapshot_unittests\": \"3ec0a2aafee4c0ef633fb1d3d3a44c15da94de16\", \"sql_unittests\": \"3172a6918f5c44ce95fe2ac3af32c2d5819fdaaa\", \"storage_unittests\": \"49a77d26019499b15929f602bc5390b1a848ce1e\", \"sync_integration_tests\": \"af112814e863bd5d96b7deedeadec8baf0c7a826\", \"traffic_annotation_auditor_unittests\": \"e811987599136b706de142abab9527382d236b5c\", \"ui_base_unittests\": \"d052bb6fcf62343810030b54f8f5ea6b2a30e566\", \"ui_touch_selection_unittests\": \"0b407754a57763a8eb292d478449c6ac77e9a2cb\", \"unit_tests\": \"26a64e91f6ff2782d8d2afc89ead6fd8e447d57b\", \"updater_tests\": \"d3f319de475440d6a6344dad4cd50d70df36c43c\", \"url_unittests\": \"d923b4d7183ac600ae843bd62ea1f198cd65d692\", \"views_unittests\": \"280211927247f91ae62a7a938c50277042710692\", \"viz_unittests\": \"e8316ab1b91bb9de4028c2389f58e995aaa80e3b\", \"vr_common_unittests\": \"7d14f4e2ebf55ea5f2aa6286b4568e50c74e9563\", \"vr_pixeltests\": \"8c57c326100189cb14cffa35e7b7213a362b60b1\", \"weblayer_browsertests\": \"5e8ad6a192fb68914190d4a0d85c9ca895d9b14e\", \"weblayer_unittests\": \"939bc26d64d2a15f87d1d4e60192df8482485da5\", \"wm_unittests\": \"bb52d8d3c28b4b49def85e4d272b6e2b975eb7b9\", \"wtf_unittests\": \"e6b78d736cd13b6416b2caa772325e7a64251b1d\", \"zlib_unittests\": \"77a45042a0bb18060f896642d17a9060dac21861\", \"zucchini_unittests\": \"842b55f2ba3e0f5900c7d83cbfef9f2ee86a79a7\"}}, \"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-1-kqo6\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5613-2fddb4d\"], \"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": "1603785082206227",
"status": "COMPLETED",
"status_changed_ts": "1603788739207080",
"tags": [
"build_address:luci.chromium.ci/win-asan/15300",
"builder:win-asan",
"buildset:commit/git/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98b751e83dba5fd24d2a95f28b3a6a9789ee3edd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030733235475293792",
"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/8865303173332991200/+/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:4f81dbc3248aa110",
"user_agent:luci-scheduler"
],
"updated_ts": "1603788739211445",
"url": "https://ci.chromium.org/b/8865303173332991200",
"utcnow_ts": "1603788739786973"
}
}
[08:52:19.798] Invocation finished in 1h1m2.579912309s with status SUCCEEDED