[00:07:13.883] New invocation is queued and will start shortly
[00:07:15.014] Starting the invocation (attempt 1)
[00:07:15.087] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:07:15.088] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:07:15.088] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027410343587725648",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"380f247530d50ac833b67a1fabcbd8e61575da2f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027410343587725648",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/380f247530d50ac833b67a1fabcbd8e61575da2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/380f247530d50ac833b67a1fabcbd8e61575da2f",
"gitiles_ref:refs/heads/master"
]
}
[00:07:16.283] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1606954035588811",
"id": "8861980281137752880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"380f247530d50ac833b67a1fabcbd8e61575da2f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606954036107461",
"tags": [
"build_address:luci.chromium.ci/win-asan/15994",
"builder:win-asan",
"buildset:commit/git/380f247530d50ac833b67a1fabcbd8e61575da2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/380f247530d50ac833b67a1fabcbd8e61575da2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027410343587725648",
"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/8861980281137752880/+/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": "1606954036107960",
"url": "https://ci.chromium.org/b/8861980281137752880",
"utcnow_ts": "1606954036272848"
}
}
[00:07:16.283] Task URL: https://ci.chromium.org/b/8861980281137752880
[00:07:16.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:2:0) after 1m0s
[00:07:26.186] Received PubSub notification, asking Buildbucket for the build status
[00:07:26.303] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:16.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:2:0)
[00:08:16.323] Timer tick, asking Buildbucket for the build status
[00:08:16.496] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:16.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:4:0) after 1m0s
[00:09:16.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:4:0)
[00:09:16.515] Timer tick, asking Buildbucket for the build status
[00:09:16.612] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:16.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:5:0) after 1m0s
[00:10:16.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:5:0)
[00:10:16.818] Timer tick, asking Buildbucket for the build status
[00:10:17.008] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:17.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:6:0) after 1m0s
[00:11:17.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:6:0)
[00:11:17.076] Timer tick, asking Buildbucket for the build status
[00:11:17.397] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:17.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:7:0) after 1m0s
[00:12:17.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:7:0)
[00:12:17.421] Timer tick, asking Buildbucket for the build status
[00:12:17.527] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:17.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:8:0) after 1m0s
[00:13:17.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:8:0)
[00:13:17.556] Timer tick, asking Buildbucket for the build status
[00:13:17.934] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:17.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:9:0) after 1m0s
[00:14:17.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:9:0)
[00:14:17.964] Timer tick, asking Buildbucket for the build status
[00:14:18.042] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:18.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:10:0) after 1m0s
[00:15:18.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:10:0)
[00:15:18.100] Timer tick, asking Buildbucket for the build status
[00:15:18.179] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:18.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:11:0) after 1m0s
[00:16:18.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:11:0)
[00:16:18.270] Timer tick, asking Buildbucket for the build status
[00:16:18.475] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:18.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:12:0) after 1m0s
[00:17:18.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:12:0)
[00:17:18.571] Timer tick, asking Buildbucket for the build status
[00:17:18.835] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:13:0) after 1m0s
[00:18:18.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:13:0)
[00:18:18.884] Timer tick, asking Buildbucket for the build status
[00:18:19.159] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:14:0) after 1m0s
[00:19:19.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:14:0)
[00:19:19.212] Timer tick, asking Buildbucket for the build status
[00:19:19.332] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:19.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:15:0) after 1m0s
[00:20:19.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:15:0)
[00:20:19.334] Timer tick, asking Buildbucket for the build status
[00:20:19.610] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:19.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:16:0) after 1m0s
[00:21:19.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:16:0)
[00:21:19.631] Timer tick, asking Buildbucket for the build status
[00:21:19.713] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:19.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:17:0) after 1m0s
[00:22:19.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:17:0)
[00:22:19.697] Timer tick, asking Buildbucket for the build status
[00:22:20.079] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:20.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:18:0) after 1m0s
[00:23:20.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:18:0)
[00:23:20.254] Timer tick, asking Buildbucket for the build status
[00:23:20.446] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:20.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:19:0) after 1m0s
[00:24:20.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:19:0)
[00:24:20.535] Timer tick, asking Buildbucket for the build status
[00:24:20.704] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:20.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:20:0) after 1m0s
[00:25:20.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:20:0)
[00:25:20.763] Timer tick, asking Buildbucket for the build status
[00:25:21.139] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:21.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:21:0) after 1m0s
[00:26:21.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:21:0)
[00:26:21.158] Timer tick, asking Buildbucket for the build status
[00:26:21.426] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:21.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:22:0) after 1m0s
[00:27:21.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:22:0)
[00:27:21.439] Timer tick, asking Buildbucket for the build status
[00:27:21.560] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:21.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:23:0) after 1m0s
[00:28:21.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:23:0)
[00:28:21.554] Timer tick, asking Buildbucket for the build status
[00:28:22.022] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:22.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:24:0) after 1m0s
[00:29:22.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:24:0)
[00:29:22.046] Timer tick, asking Buildbucket for the build status
[00:29:22.349] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:22.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:25:0) after 1m0s
[00:30:22.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:25:0)
[00:30:22.372] Timer tick, asking Buildbucket for the build status
[00:30:22.645] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:22.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:26:0) after 1m0s
[00:31:22.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:26:0)
[00:31:22.685] Timer tick, asking Buildbucket for the build status
[00:31:22.829] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:22.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:27:0) after 1m0s
[00:32:22.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:27:0)
[00:32:22.883] Timer tick, asking Buildbucket for the build status
[00:32:23.067] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:23.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:28:0) after 1m0s
[00:33:23.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:28:0)
[00:33:23.151] Timer tick, asking Buildbucket for the build status
[00:33:23.270] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:23.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:29:0) after 1m0s
[00:34:23.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:29:0)
[00:34:23.311] Timer tick, asking Buildbucket for the build status
[00:34:23.551] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:23.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:30:0) after 1m0s
[00:35:23.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:30:0)
[00:35:23.573] Timer tick, asking Buildbucket for the build status
[00:35:23.970] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:23.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:31:0) after 1m0s
[00:36:24.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:31:0)
[00:36:24.035] Timer tick, asking Buildbucket for the build status
[00:36:24.226] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:24.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:32:0) after 1m0s
[00:37:24.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:32:0)
[00:37:24.282] Timer tick, asking Buildbucket for the build status
[00:37:24.731] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:24.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:33:0) after 1m0s
[00:38:24.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:33:0)
[00:38:24.747] Timer tick, asking Buildbucket for the build status
[00:38:24.886] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:24.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:34:0) after 1m0s
[00:39:25.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:34:0)
[00:39:25.003] Timer tick, asking Buildbucket for the build status
[00:39:25.143] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:25.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:35:0) after 1m0s
[00:40:25.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:35:0)
[00:40:25.200] Timer tick, asking Buildbucket for the build status
[00:40:25.310] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:25.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:36:0) after 1m0s
[00:41:25.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:36:0)
[00:41:25.359] Timer tick, asking Buildbucket for the build status
[00:41:25.650] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:25.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:37:0) after 1m0s
[00:42:25.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:37:0)
[00:42:25.709] Timer tick, asking Buildbucket for the build status
[00:42:26.193] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:26.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:38:0) after 1m0s
[00:43:26.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:38:0)
[00:43:26.235] Timer tick, asking Buildbucket for the build status
[00:43:26.367] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:26.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:39:0) after 1m0s
[00:44:26.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:39:0)
[00:44:26.519] Timer tick, asking Buildbucket for the build status
[00:44:27.033] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:27.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:40:0) after 1m0s
[00:45:27.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:40:0)
[00:45:27.045] Timer tick, asking Buildbucket for the build status
[00:45:27.297] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:27.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:41:0) after 1m0s
[00:46:27.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:41:0)
[00:46:27.322] Timer tick, asking Buildbucket for the build status
[00:46:27.435] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:27.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:42:0) after 1m0s
[00:47:27.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:42:0)
[00:47:27.469] Timer tick, asking Buildbucket for the build status
[00:47:27.814] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:27.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:43:0) after 1m0s
[00:48:27.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:43:0)
[00:48:27.849] Timer tick, asking Buildbucket for the build status
[00:48:27.947] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:44:0) after 1m0s
[00:49:27.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:44:0)
[00:49:27.963] Timer tick, asking Buildbucket for the build status
[00:49:28.027] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:28.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:45:0) after 1m0s
[00:50:28.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:45:0)
[00:50:28.117] Timer tick, asking Buildbucket for the build status
[00:50:28.289] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:28.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:46:0) after 1m0s
[00:51:28.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:46:0)
[00:51:28.298] Timer tick, asking Buildbucket for the build status
[00:51:28.393] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:28.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:47:0) after 1m0s
[00:52:28.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:47:0)
[00:52:28.465] Timer tick, asking Buildbucket for the build status
[00:52:28.648] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:28.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:48:0) after 1m0s
[00:53:28.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:48:0)
[00:53:28.789] Timer tick, asking Buildbucket for the build status
[00:53:29.054] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:29.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:49:0) after 1m0s
[00:54:29.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:49:0)
[00:54:29.163] Timer tick, asking Buildbucket for the build status
[00:54:29.342] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:29.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:50:0) after 1m0s
[00:55:29.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:50:0)
[00:55:29.402] Timer tick, asking Buildbucket for the build status
[00:55:29.733] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:29.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:51:0) after 1m0s
[00:56:29.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:51:0)
[00:56:29.796] Timer tick, asking Buildbucket for the build status
[00:56:30.713] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:52:0) after 1m0s
[00:57:30.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:52:0)
[00:57:30.756] Timer tick, asking Buildbucket for the build status
[00:57:32.073] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:32.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:53:0) after 1m0s
[00:58:32.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:53:0)
[00:58:32.171] Timer tick, asking Buildbucket for the build status
[00:58:32.811] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:32.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:54:0) after 1m0s
[00:59:32.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:54:0)
[00:59:32.858] Timer tick, asking Buildbucket for the build status
[00:59:33.086] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:33.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:55:0) after 1m0s
[01:00:33.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:55:0)
[01:00:33.122] Timer tick, asking Buildbucket for the build status
[01:00:33.249] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:33.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:56:0) after 1m0s
[01:01:33.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:56:0)
[01:01:33.293] Timer tick, asking Buildbucket for the build status
[01:01:33.382] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:33.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:57:0) after 1m0s
[01:02:33.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:57:0)
[01:02:33.409] Timer tick, asking Buildbucket for the build status
[01:02:33.716] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:33.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:58:0) after 1m0s
[01:03:33.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:58:0)
[01:03:33.747] Timer tick, asking Buildbucket for the build status
[01:03:34.037] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:34.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:59:0) after 1m0s
[01:04:34.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:59:0)
[01:04:34.068] Timer tick, asking Buildbucket for the build status
[01:04:34.198] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:34.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:60:0) after 1m0s
[01:05:34.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:60:0)
[01:05:34.233] Timer tick, asking Buildbucket for the build status
[01:05:34.357] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:34.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:61:0) after 1m0s
[01:06:34.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:61:0)
[01:06:34.475] Timer tick, asking Buildbucket for the build status
[01:06:35.643] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:35.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:62:0) after 1m0s
[01:07:35.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:62:0)
[01:07:35.753] Timer tick, asking Buildbucket for the build status
[01:07:35.890] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:35.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:63:0) after 1m0s
[01:08:35.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:63:0)
[01:08:35.956] Timer tick, asking Buildbucket for the build status
[01:08:36.057] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:64:0) after 1m0s
[01:09:36.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:64:0)
[01:09:36.085] Timer tick, asking Buildbucket for the build status
[01:09:36.184] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:36.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:65:0) after 1m0s
[01:10:36.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:65:0)
[01:10:36.234] Timer tick, asking Buildbucket for the build status
[01:10:36.323] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:36.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:66:0) after 1m0s
[01:11:36.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:66:0)
[01:11:36.372] Timer tick, asking Buildbucket for the build status
[01:11:36.675] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:36.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:67:0) after 1m0s
[01:12:36.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:67:0)
[01:12:36.702] Timer tick, asking Buildbucket for the build status
[01:12:37.790] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:37.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:68:0) after 1m0s
[01:13:37.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:68:0)
[01:13:37.791] Timer tick, asking Buildbucket for the build status
[01:13:37.897] Build 8861980281137752880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:37.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027410343587725648:69:0) after 1m0s
[01:14:16.005] Received PubSub notification, asking Buildbucket for the build status
[01:14:16.298] Build 8861980281137752880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:14:16.298] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1606958054288048",
"created_by": "project:chromium",
"created_ts": "1606954035588811",
"id": "8861980281137752880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"380f247530d50ac833b67a1fabcbd8e61575da2f\"}}",
"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\": \"380f247530d50ac833b67a1fabcbd8e61575da2f\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15994, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"8797714c4a78082270473e4b4bed18b4a60f6245\", \"got_dawn_revision\": \"429a12542f160a36d87c1daa6c3102bce072207e\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"380f247530d50ac833b67a1fabcbd8e61575da2f\", \"got_revision_cp\": \"refs/heads/master@{#833051}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"95ffe036632c5de2e52a01d243e945a1123c91a2\", \"got_v8_revision\": \"252a9ed08d1203226b951518a5ec1113a22b362f\", \"got_v8_revision_cp\": \"refs/heads/8.9.76@{#1}\", \"got_webrtc_revision\": \"04ee79c749be019fde4006e4ff1a1690c9c9dd85\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32744}\", \"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\": \"380f247530d50ac833b67a1fabcbd8e61575da2f\", \"swarm_hashes_refs/heads/master(at){#833051}_without_patch\": {\"absl_hardening_tests\": \"23b907c7250388eb8a4f371acf89f5463eda896b\", \"accessibility_unittests\": \"3a936d639f6a3de231e51d0b93a7e1bff6fd9fee\", \"angle_unittests\": \"e877a9d1301e2b9dc4d3e13f3bdd209c8e97e10f\", \"app_shell_unittests\": \"58da6dc4d97fa9622e3664eef17f386176a3d367\", \"aura_unittests\": \"a24527b0569fc19cd97d94bb71144f15e1257a25\", \"base_unittests\": \"788d8cdcbc89ef330b3ed70325c8caf19c6238b0\", \"base_util_unittests\": \"13541ee251671d5ce9470157bbdaa7e8a6ab3f27\", \"blink_common_unittests\": \"e5259ade208c8d82fedb859cfd04424f2d31fb2b\", \"blink_fuzzer_unittests\": \"9150ab08238fd80b511a1e599183e06fad842a4c\", \"blink_heap_unittests\": \"eb57fc03337dec62b0ad27af1ec9b6bdbe2a399f\", \"blink_platform_unittests\": \"17b95aa622003c8acbbccf22a756d5db8158d2c2\", \"blink_unittests\": \"b89fad132ace56c63278847bbf7c37ec3c83ffb4\", \"boringssl_crypto_tests\": \"2678a3ea81c62f12b04d1d32101e7405dc5cdaff\", \"boringssl_ssl_tests\": \"fa442c477ea7a77a3bf164bb94525e0f77e4a430\", \"browser_switcher_bho_unittests\": \"c6317cd4c15961d0597b4a0c587ffd44f0f08f3d\", \"browser_tests\": \"ac755175a243951ec01febc4724f364392f57bca\", \"capture_unittests\": \"ecc5dc524e952969449fed7205a28e8a89c36b70\", \"cast_unittests\": \"c9d6c6df19b708c22d1f19da379b2c62c0f40267\", \"cc_unittests\": \"1ae989ff36bcfa7d85ce8399285c7c370e0cba26\", \"chrome_app_unittests\": \"d7273abd567233e04105375208cec855971fe466\", \"chrome_cleaner_unittests\": \"a1b9d0a12250ce697a101f337f297ff1d8377c05\", \"chrome_elf_unittests\": \"9e9ed69837b1cde8ffba058303c0c306c8536481\", \"chromedriver_unittests\": \"c3e7420655fd2a22c794e03615aa88d80b0e4c64\", \"components_browsertests\": \"eb5c051e6188c02d976af33dcb867bdd79f867f4\", \"components_unittests\": \"27fbe65d57467d69b0c9d0d9a7f09b633094952c\", \"compositor_unittests\": \"f15c83dd3dcfea3641fc7dbde1367e72c9846f85\", \"content_browsertests\": \"c81db0f6e7dc31ab01bda2b15f1c57e63da5d57b\", \"content_unittests\": \"afc9a5720457fb5f89d56c3ce158e846e6919660\", \"courgette_unittests\": \"ace4cdf146b7a9728ed09a204ca9de963488ed7d\", \"crashpad_tests\": \"8b884d4d6284544417a18210d24e3d7be5b2ba35\", \"cronet_tests\": \"960051228b679269853f7b992a93122f31e4ca90\", \"cronet_unittests\": \"90bc281bea513fc9ed35fc462fdfb4c9b431e50b\", \"crypto_unittests\": \"0c3fb0ca23f49c8586983164652c2487f35004bb\", \"delayloads_unittests\": \"cd0c6a60fb05f0b8420c31b36f58ec02035b73f5\", \"device_unittests\": \"031f0607eaeee1640fee708428c55df214684f22\", \"display_unittests\": \"874a3a65c4714ef778ff862ddf6305c3036442e4\", \"elevation_service_unittests\": \"6dc0fb4aeabb37caa315140b9a7a9d643de80c02\", \"events_unittests\": \"9c7055d133ca2a4402b963c4bf6dcfec3f9251d7\", \"extensions_browsertests\": \"d4b977b7f50f394a454902cf6f833a56a70da240\", \"extensions_unittests\": \"0d86c283cef33a7bbd0645c9c3170a790571b9d5\", \"filesystem_service_unittests\": \"8ace15261c98911fbbaef4004c4f28f821818c7d\", \"gcm_unit_tests\": \"337944bf460244f1518e1e3e132de4c24b243de7\", \"gcp_unittests\": \"e3785ffed87bd242fa801ec295036e9563d219f1\", \"gfx_unittests\": \"ebdb6745d8ce227198e391c75a092a65af4fe89b\", \"gin_unittests\": \"5ae0f263938cad3ead3bf93bf42d9c69f938554f\", \"google_apis_unittests\": \"6c0919188b4ba76572d3424afbb056a67408cb29\", \"gpu_unittests\": \"df38e16ce20c7cc8c31c159b37f517539784e056\", \"gwp_asan_unittests\": \"2aaf874f2e8c396ccc80c6ee12a86756eae76ee7\", \"headless_browsertests\": \"bb0654a2c9f719f1fcc75322e71ab2c5ff6db78c\", \"headless_unittests\": \"13aa5dbd4cfcef4c28d835a23787e74b2cd92e0a\", \"install_static_unittests\": \"721ebb15202128214a362562f88a0da438075971\", \"installer_util_unittests\": \"6a107bf44a13deb3d334c19c41b0dd85e74107a5\", \"interactive_ui_tests\": \"40d54a3c067109fa0c24bdd42e99b903de57fa4a\", \"ipc_tests\": \"b68b10a735e799ccc89423b2d639197a2943ec3e\", \"jingle_unittests\": \"a81d30efa574608a2758f4fbc164b93a40584240\", \"latency_unittests\": \"5d4b4d5495adfe84dffc4216b9bc863bc417fb76\", \"libjingle_xmpp_unittests\": \"673073ba4e69cab00d05f283e2a20f10b42d76db\", \"liburlpattern_unittests\": \"020e97902abb9d08fad6c7ad77496c52a721b506\", \"media_blink_unittests\": \"7183d4bd61674a0f12dc99a6e4f2fac78de0cd2c\", \"media_unittests\": \"927702f6ab76595934d3c27d492dfb03a264c103\", \"message_center_unittests\": \"2bc6d0e7494218d8869cf43fc59b5c3a750be99a\", \"midi_unittests\": \"d5fb14cceaa08007772344fa886e325909e380d5\", \"mojo_core_unittests\": \"e0fc542ef7a254cbf823e094a7623382610ea964\", \"mojo_unittests\": \"0077f7dccc61e27cb08c56449eac2f81794d2f61\", \"nacl_loader_unittests\": \"6579d8fead4e10d2b270acd56dc4ae3b619a242f\", \"native_theme_unittests\": \"7376c9c7b4cfc6a73fa84290180f01e73e3429ec\", \"net_unittests\": \"b6eabca7277ebc4cfa3379282ccae33de07f5975\", \"notification_helper_unittests\": \"e1c83a590bd543857750d8a62b4d0b638df3a8be\", \"pdf_unittests\": \"77882f12eae70b0d5b45bf17f668eaaf5ddf18b3\", \"perfetto_unittests\": \"a833ad83bcda158146d09802dab1abba5d79395f\", \"ppapi_unittests\": \"29d23fa998aadee8499df2560142372ff4e18bb3\", \"printing_unittests\": \"0b4d81ff221692faf5217b9a68bfc883b74ae03c\", \"remoting_unittests\": \"255f28de2b0d6c72d72f6b15e067e566601383c1\", \"sbox_integration_tests\": \"7cfc4aecfe8784b9069df77ad9c93e49656e5c68\", \"sbox_unittests\": \"6c7b10069649bbc4e5343a047a6b542c24ff6473\", \"sbox_validation_tests\": \"0cdfbc090d56a958d6e29158b74fa097f7990a1d\", \"service_manager_unittests\": \"a303b00838c2ad4dc300ea7d6dab32bca47cf8ee\", \"services_unittests\": \"00602e706b136230cdf339f460beeafd37a1f873\", \"setup_unittests\": \"53ff024abf2e7ade4236a610122574f12c2279fc\", \"shell_dialogs_unittests\": \"45dd55710ce7de5c1cb83492b7cce0227398f3c1\", \"skia_unittests\": \"cc0f258901a7e2fc30d2cf31098d709a1a906d4d\", \"snapshot_unittests\": \"5f8e17927f8456ab18403474758f666c8d384ada\", \"sql_unittests\": \"bb37b59492cb26c90a302df5b4f20a04e2519ed7\", \"storage_unittests\": \"9fdac4a6473229c2cec597f4db5dd1e2c10cb1fb\", \"sync_integration_tests\": \"7306a19d6f91f107012304e31ae43c159458ff44\", \"traffic_annotation_auditor_unittests\": \"8728b78c0a4ffdce3bc100fe0a957bdd9dff0c84\", \"ui_base_unittests\": \"00ba15cde270da188ec5020e30f6ee286aacd07c\", \"ui_touch_selection_unittests\": \"2fef65d84bf71febbfef68ab5f48a1845d8ba68b\", \"unit_tests\": \"5590b22e4cb5dd4601f2faaba0ff2c054cd1699b\", \"updater_tests\": \"f71353e93fd1f06be3638dc2360b022475702c91\", \"url_unittests\": \"3db20adf3a679e1db72b9ac9c39ecbfaa7ae1800\", \"views_unittests\": \"23ec6a2f06bf27d861ae9696a59dd29ca93f3763\", \"viz_unittests\": \"76329a2b561351dd7a2036d84ff19b649ff64d76\", \"vr_common_unittests\": \"b48df53279482a56f929f665b1411ca6dc77b2bb\", \"vr_pixeltests\": \"df9a34f8b4341f45ede8b3fd12910fb51dea35f6\", \"weblayer_browsertests\": \"5960f49cc8593da2ee4f7af8acfaf8361a3596b6\", \"weblayer_unittests\": \"ae5f17183927af722b6d8f03164ee35bdaa24f63\", \"wm_unittests\": \"d0fce032ae44783c0aad6dd08ca5d89c43363ec5\", \"wtf_unittests\": \"bf3f70af91fbf412f3c320aabc305b2684035513\", \"zlib_unittests\": \"25590b273a7f87bbed881970f326639aacfae89f\", \"zucchini_unittests\": \"ebbf3da88fc872afef0dce3515b2d26bf7d3093b\"}}, \"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-9-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1606954044909198",
"status": "COMPLETED",
"status_changed_ts": "1606958054690669",
"tags": [
"build_address:luci.chromium.ci/win-asan/15994",
"builder:win-asan",
"buildset:commit/git/380f247530d50ac833b67a1fabcbd8e61575da2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/380f247530d50ac833b67a1fabcbd8e61575da2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027410343587725648",
"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/8861980281137752880/+/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:503ebe44f6906810",
"user_agent:luci-scheduler"
],
"updated_ts": "1606958054695356",
"url": "https://ci.chromium.org/b/8861980281137752880",
"utcnow_ts": "1606958056258151"
}
}
[01:14:16.298] Invocation finished in 1h7m2.430839129s with status SUCCEEDED