[04:09:26.745] New invocation is queued and will start shortly
[04:09:28.011] Starting the invocation (attempt 1)
[04:09:28.045] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:09:28.045] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:09:28.045] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022140480726629360",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5aa8ab09d508d6745e7b7857c90f1421577155c3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022140480726629360",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"gitiles_ref:refs/heads/master"
]
}
[04:09:29.445] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611979768138493",
"id": "8856710418603460912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5aa8ab09d508d6745e7b7857c90f1421577155c3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611979769191959",
"tags": [
"build_address:luci.chromium.ci/win-asan/17080",
"builder:win-asan",
"buildset:commit/git/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022140480726629360",
"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/8856710418603460912/+/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": "1611979769192162",
"url": "https://ci.chromium.org/b/8856710418603460912",
"utcnow_ts": "1611979769433313"
}
}
[04:09:29.445] Task URL: https://ci.chromium.org/b/8856710418603460912
[04:09:29.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:2:0) after 1m0s
[04:09:34.254] Received PubSub notification, asking Buildbucket for the build status
[04:09:34.334] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:2:0)
[04:10:29.446] Timer tick, asking Buildbucket for the build status
[04:10:29.524] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:4:0) after 1m0s
[04:11:29.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:4:0)
[04:11:29.545] Timer tick, asking Buildbucket for the build status
[04:11:29.658] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:29.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:5:0) after 1m0s
[04:12:29.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:5:0)
[04:12:29.753] Timer tick, asking Buildbucket for the build status
[04:12:30.417] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:30.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:6:0) after 1m0s
[04:13:30.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:6:0)
[04:13:30.477] Timer tick, asking Buildbucket for the build status
[04:13:30.654] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:30.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:7:0) after 1m0s
[04:14:30.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:7:0)
[04:14:30.721] Timer tick, asking Buildbucket for the build status
[04:14:30.818] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:30.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:8:0) after 1m0s
[04:15:30.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:8:0)
[04:15:30.909] Timer tick, asking Buildbucket for the build status
[04:15:31.311] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:31.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:9:0) after 1m0s
[04:16:31.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:9:0)
[04:16:31.316] Timer tick, asking Buildbucket for the build status
[04:16:31.438] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:31.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:10:0) after 1m0s
[04:17:31.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:10:0)
[04:17:31.561] Timer tick, asking Buildbucket for the build status
[04:17:31.625] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:31.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:11:0) after 1m0s
[04:18:31.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:11:0)
[04:18:31.662] Timer tick, asking Buildbucket for the build status
[04:18:31.740] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:31.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:12:0) after 1m0s
[04:19:31.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:12:0)
[04:19:31.824] Timer tick, asking Buildbucket for the build status
[04:19:31.919] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:31.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:13:0) after 1m0s
[04:20:32.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:13:0)
[04:20:32.001] Timer tick, asking Buildbucket for the build status
[04:20:32.239] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:32.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:14:0) after 1m0s
[04:21:32.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:14:0)
[04:21:32.319] Timer tick, asking Buildbucket for the build status
[04:21:32.423] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:32.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:15:0) after 1m0s
[04:22:32.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:15:0)
[04:22:32.454] Timer tick, asking Buildbucket for the build status
[04:22:32.571] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:32.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:16:0) after 1m0s
[04:23:32.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:16:0)
[04:23:32.610] Timer tick, asking Buildbucket for the build status
[04:23:32.670] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:32.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:17:0) after 1m0s
[04:24:32.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:17:0)
[04:24:32.715] Timer tick, asking Buildbucket for the build status
[04:24:33.001] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:33.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:18:0) after 1m0s
[04:25:33.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:18:0)
[04:25:33.021] Timer tick, asking Buildbucket for the build status
[04:25:33.218] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:33.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:19:0) after 1m0s
[04:26:33.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:19:0)
[04:26:33.237] Timer tick, asking Buildbucket for the build status
[04:26:33.609] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:33.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:20:0) after 1m0s
[04:27:33.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:20:0)
[04:27:33.681] Timer tick, asking Buildbucket for the build status
[04:27:33.746] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:33.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:21:0) after 1m0s
[04:28:33.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:21:0)
[04:28:33.829] Timer tick, asking Buildbucket for the build status
[04:28:34.043] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:34.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:22:0) after 1m0s
[04:29:34.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:22:0)
[04:29:34.039] Timer tick, asking Buildbucket for the build status
[04:29:34.600] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:34.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:23:0) after 1m0s
[04:30:34.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:23:0)
[04:30:34.696] Timer tick, asking Buildbucket for the build status
[04:30:34.937] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:34.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:24:0) after 1m0s
[04:31:35.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:24:0)
[04:31:35.055] Timer tick, asking Buildbucket for the build status
[04:31:35.638] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:35.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:25:0) after 1m0s
[04:32:35.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:25:0)
[04:32:35.710] Timer tick, asking Buildbucket for the build status
[04:32:36.134] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:36.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:26:0) after 1m0s
[04:33:36.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:26:0)
[04:33:36.133] Timer tick, asking Buildbucket for the build status
[04:33:36.245] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:36.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:27:0) after 1m0s
[04:34:36.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:27:0)
[04:34:36.315] Timer tick, asking Buildbucket for the build status
[04:34:36.426] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:36.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:28:0) after 1m0s
[04:35:36.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:28:0)
[04:35:36.420] Timer tick, asking Buildbucket for the build status
[04:35:36.541] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:36.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:29:0) after 1m0s
[04:36:36.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:29:0)
[04:36:36.571] Timer tick, asking Buildbucket for the build status
[04:36:36.720] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:36.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:30:0) after 1m0s
[04:37:36.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:30:0)
[04:37:36.757] Timer tick, asking Buildbucket for the build status
[04:37:37.120] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:37.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:31:0) after 1m0s
[04:38:37.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:31:0)
[04:38:37.187] Timer tick, asking Buildbucket for the build status
[04:38:37.443] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:37.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:32:0) after 1m0s
[04:39:37.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:32:0)
[04:39:37.489] Timer tick, asking Buildbucket for the build status
[04:39:37.739] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:37.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:33:0) after 1m0s
[04:40:37.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:33:0)
[04:40:37.781] Timer tick, asking Buildbucket for the build status
[04:40:37.925] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:37.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:34:0) after 1m0s
[04:41:37.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:34:0)
[04:41:37.944] Timer tick, asking Buildbucket for the build status
[04:41:38.083] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:38.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:35:0) after 1m0s
[04:42:38.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:35:0)
[04:42:38.105] Timer tick, asking Buildbucket for the build status
[04:42:38.202] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:38.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:36:0) after 1m0s
[04:43:38.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:36:0)
[04:43:38.224] Timer tick, asking Buildbucket for the build status
[04:43:38.337] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:38.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:37:0) after 1m0s
[04:44:38.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:37:0)
[04:44:38.388] Timer tick, asking Buildbucket for the build status
[04:44:38.503] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:38.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:38:0) after 1m0s
[04:45:38.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:38:0)
[04:45:38.522] Timer tick, asking Buildbucket for the build status
[04:45:38.979] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:38.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:39:0) after 1m0s
[04:46:39.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:39:0)
[04:46:39.026] Timer tick, asking Buildbucket for the build status
[04:46:39.666] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:39.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:40:0) after 1m0s
[04:47:39.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:40:0)
[04:47:39.692] Timer tick, asking Buildbucket for the build status
[04:47:39.768] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:41:0) after 1m0s
[04:48:39.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:41:0)
[04:48:39.795] Timer tick, asking Buildbucket for the build status
[04:48:40.019] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:40.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:42:0) after 1m0s
[04:49:40.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:42:0)
[04:49:40.041] Timer tick, asking Buildbucket for the build status
[04:49:40.174] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:40.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:43:0) after 1m0s
[04:50:40.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:43:0)
[04:50:40.203] Timer tick, asking Buildbucket for the build status
[04:50:40.601] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:40.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:44:0) after 1m0s
[04:51:40.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:44:0)
[04:51:40.622] Timer tick, asking Buildbucket for the build status
[04:51:40.724] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:40.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:45:0) after 1m0s
[04:52:40.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:45:0)
[04:52:40.784] Timer tick, asking Buildbucket for the build status
[04:52:40.894] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:40.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:46:0) after 1m0s
[04:53:40.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:46:0)
[04:53:40.916] Timer tick, asking Buildbucket for the build status
[04:53:41.032] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:41.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:47:0) after 1m0s
[04:54:41.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:47:0)
[04:54:41.067] Timer tick, asking Buildbucket for the build status
[04:54:41.331] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:48:0) after 1m0s
[04:55:41.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:48:0)
[04:55:41.343] Timer tick, asking Buildbucket for the build status
[04:55:41.512] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:49:0) after 1m0s
[04:56:41.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:49:0)
[04:56:41.560] Timer tick, asking Buildbucket for the build status
[04:56:41.630] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:41.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:50:0) after 1m0s
[04:57:41.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:50:0)
[04:57:41.699] Timer tick, asking Buildbucket for the build status
[04:57:41.760] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:41.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:51:0) after 1m0s
[04:58:41.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:51:0)
[04:58:41.859] Timer tick, asking Buildbucket for the build status
[04:58:42.033] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:42.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:52:0) after 1m0s
[04:59:42.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:52:0)
[04:59:42.114] Timer tick, asking Buildbucket for the build status
[04:59:42.511] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:42.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:53:0) after 1m0s
[05:00:42.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:53:0)
[05:00:42.548] Timer tick, asking Buildbucket for the build status
[05:00:42.763] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:42.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:54:0) after 1m0s
[05:01:42.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:54:0)
[05:01:42.878] Timer tick, asking Buildbucket for the build status
[05:01:43.003] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:43.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:55:0) after 1m0s
[05:02:43.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:55:0)
[05:02:43.119] Timer tick, asking Buildbucket for the build status
[05:02:43.395] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:43.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:56:0) after 1m0s
[05:03:43.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:56:0)
[05:03:43.465] Timer tick, asking Buildbucket for the build status
[05:03:43.591] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:43.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:57:0) after 1m0s
[05:04:43.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:57:0)
[05:04:43.696] Timer tick, asking Buildbucket for the build status
[05:04:43.804] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:43.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:58:0) after 1m0s
[05:05:43.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:58:0)
[05:05:43.995] Timer tick, asking Buildbucket for the build status
[05:05:44.127] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:44.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:59:0) after 1m0s
[05:06:44.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:59:0)
[05:06:44.186] Timer tick, asking Buildbucket for the build status
[05:06:44.259] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:44.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:60:0) after 1m0s
[05:07:44.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:60:0)
[05:07:44.369] Timer tick, asking Buildbucket for the build status
[05:07:44.791] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:44.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:61:0) after 1m0s
[05:08:44.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:61:0)
[05:08:44.802] Timer tick, asking Buildbucket for the build status
[05:08:44.948] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:44.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:62:0) after 1m0s
[05:09:45.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:62:0)
[05:09:45.007] Timer tick, asking Buildbucket for the build status
[05:09:45.125] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:45.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:63:0) after 1m0s
[05:10:45.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:63:0)
[05:10:45.179] Timer tick, asking Buildbucket for the build status
[05:10:45.297] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:45.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:64:0) after 1m0s
[05:11:45.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:64:0)
[05:11:45.375] Timer tick, asking Buildbucket for the build status
[05:11:45.512] Build 8856710418603460912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:45.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022140480726629360:65:0) after 1m0s
[05:12:22.838] Received PubSub notification, asking Buildbucket for the build status
[05:12:22.918] Build 8856710418603460912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:12:22.918] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611983537120287",
"created_by": "project:chromium",
"created_ts": "1611979768138493",
"id": "8856710418603460912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5aa8ab09d508d6745e7b7857c90f1421577155c3\"}}",
"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\": \"5aa8ab09d508d6745e7b7857c90f1421577155c3\", \"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-7-2fjn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17080, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5aa8ab09d508d6745e7b7857c90f1421577155c3\", \"got_revision_cp\": \"refs/heads/master@{#848868}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"f9a6148e0498abdbe74eb6edf6aac78667d29499\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33109}\", \"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\": \"5aa8ab09d508d6745e7b7857c90f1421577155c3\", \"swarm_hashes_refs/heads/master(at){#848868}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"9540eb0406d4cd5ea6ec4c8a56710190ea55160d\", \"angle_unittests\": \"4cacb0a4b087962cf644ffbb65eaa7eeea10d4fb\", \"app_shell_unittests\": \"e4205ef49dbab4af610dff14ebe2b86eadec28b9\", \"aura_unittests\": \"a4a5bbf6f6e8f28d4d0a0f24e5bcc4ea10687f7e\", \"base_unittests\": \"cb9a5512177782fd46758f092efce914e7805fca\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"f0d2c1f2577b818038c7398e6d7286a929c6464a\", \"blink_fuzzer_unittests\": \"b8d9c8da1aaca23075ae4cb77e93ce295d8b593f\", \"blink_heap_unittests\": \"12b760d0a09f50ac27f7ef4d6d9dfa80d53e91b7\", \"blink_platform_unittests\": \"9653eb429a75945fd4ec5f1c536d0eb00de17f9a\", \"blink_unittests\": \"934529a27bceff523139d770f47340df7c2bbe1c\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"e32f92e12058ae11a5f1bfdb27457dd951d419b8\", \"capture_unittests\": \"067e5c1cf159221d564460f8650b1e7abea664c6\", \"cast_unittests\": \"9968c19162b98dea522691758b94f1d7d5e77ba8\", \"cc_unittests\": \"2682a83747b01b57c05ce60b456c64fb3b9d39ff\", \"chrome_app_unittests\": \"3ef2ff514b95c8b3674ecacf07f8e1875ae34000\", \"chrome_cleaner_unittests\": \"7c71fb68e5a718c87a4828672f00b2e15e2f7b5b\", \"chrome_elf_unittests\": \"bed7ce191542720c2c707d0b06f2d696307bec19\", \"chromedriver_unittests\": \"98c2f02104ae7a68d1aa03321fe835b663d731ec\", \"components_browsertests\": \"96a015e8fe9b54bc75730f73fc012402a54cc912\", \"components_unittests\": \"e01596d528ec7b33c36f38a9c6c12764bd2aa0c7\", \"compositor_unittests\": \"f6e25ac68b1794b70118e05270582f986da9045c\", \"content_browsertests\": \"9c9e25d8f2e6e4ca69478a7a909b18cb1e6fce7b\", \"content_unittests\": \"4db505d01e1b8e6c4af80d50da8a63ffb68f875d\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"4c1499b9404e086b1bd2ca41b5e03615e010282a\", \"cronet_unittests\": \"7cee9cd5fc95cbb6ee8599cf7078d2d540fea0e1\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"ccedfa2c65c19355aee7e96bf2d2203554f5f988\", \"device_unittests\": \"803a48aaf0fb92ccec166057b20cea9f08bcc998\", \"display_unittests\": \"50dc24adb1e31d684b85cbd575b40a3d954f6a2f\", \"elevation_service_unittests\": \"15fc9e2483dc5dc781213262a1c7cc7a76a8df20\", \"events_unittests\": \"99ddce3085d6976aa340927b1c5b21c169fb7f64\", \"extensions_browsertests\": \"8ea601edc0fdd20606e63afd87fe470f4d8fd578\", \"extensions_unittests\": \"b1e11d96f7cf10763be48f1419ecd99a1341d96a\", \"filesystem_service_unittests\": \"6eb5d28ae2beaffe8509e5cb83ba9ddaeabca912\", \"gcm_unit_tests\": \"c3faac7a23556a27c28f729b09f015b60bccad07\", \"gcp_unittests\": \"5a5e56ead53d91a9e154bb207ad9eedbf839f377\", \"gfx_unittests\": \"70538119fd4dbaf4ee9eaef62de28270f4f6f6ed\", \"gin_unittests\": \"9ce5a157335b91d97148d59f85e5c34b22a8c229\", \"google_apis_unittests\": \"a68a5f6c6f55ea15b0bf52c114d5c08a68e98c81\", \"gpu_unittests\": \"f77b20583948734f9208c6518fbd7bc5deb59bc6\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"7a62bea148025691633c2e59194ccc70eaeddc4d\", \"headless_unittests\": \"2092ad039c43cce6ea8ffbc5a0972edd1ab1dbb9\", \"install_static_unittests\": \"b4b79dbb9c2441af02e563595c15d290c4637487\", \"installer_util_unittests\": \"8e14aa0553ee737b551450e18a866304c0d79af8\", \"interactive_ui_tests\": \"457d20a7b40fcdf5549ef68b594c139403dbe0c7\", \"ipc_tests\": \"b686e8935e0a979c0c0b3b4767f32422d0365a7a\", \"jingle_unittests\": \"bad27c64a9372e75a6b5c58ada03a6b77d06525f\", \"latency_unittests\": \"23bd8ae273fab16b1be22bf2c0cf57a29e87b1a6\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"d9f7aaad4e4f4d3d899aae8945e24bf3a5564c74\", \"media_unittests\": \"81d8b3762236f182baa784ea8792fafceda4c501\", \"message_center_unittests\": \"cefd5cb446c463ca928ba51b1e6d674ef3714bc6\", \"midi_unittests\": \"e3855ab0472d27d8764da6194b748d40aee795d9\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"0bbedfc321305f5308d4d87d3ff608221355a6bb\", \"nacl_loader_unittests\": \"cfd07a17cf51606a82c99cd4d92c9ac7050b5610\", \"native_theme_unittests\": \"d003117af9ba80e40f820200941a960a9acf1d68\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"eade004feb7af03150484869b6d8a03a672e12be\", \"pdf_unittests\": \"d611cb54ad711010b83048f4063f1a2ac8850f87\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"bac4242efaaa4936046dbd468ff02b06e2236387\", \"printing_unittests\": \"2fae86be379f77b683495068f225f36a6521cdfc\", \"remoting_unittests\": \"949e2f34c9f2729498bec8a820ab83458f48c3ca\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"371d13bf73866c890b4bb9488f143d27f20cc09b\", \"services_unittests\": \"0b6b69a538b158b595639445b137833266658980\", \"setup_unittests\": \"10c7b9984c1dd160ed1e78a4b746c4b40caab5ae\", \"shell_dialogs_unittests\": \"abb6326231f2a25367696db51faab9f2c0aabde8\", \"skia_unittests\": \"0a8bd7317c4f4175a68b5d6fb8f74af3d10da17b\", \"snapshot_unittests\": \"7652f7a45444d73e22e3795bbccb805196ba9f95\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"0e2d9f24b8ce9a276ede2b1b276400b71a231d3c\", \"sync_integration_tests\": \"c61564b9b199e8889eb04b06e322d3aa84691911\", \"traffic_annotation_auditor_unittests\": \"f7731a5bdc05e5f89a908c2e79bfc57d12bbe13e\", \"ui_base_unittests\": \"b06e084c6fe978f1c5071b93ce6873ab1cad73e8\", \"ui_touch_selection_unittests\": \"7379cda73534ed9e83e2208ff3b3d1539695f0bb\", \"unit_tests\": \"3b0b6e7d4865e710b849271bd8cb40ef38e01e0b\", \"updater_tests\": \"51fb3f91ff1960fe62a603984df1e91141a27762\", \"url_unittests\": \"fcaa983f6b8ad52539121b4e9b3a2a5658b84987\", \"views_unittests\": \"be3d28d5ec4f325dc847853adbf3feefe8a9859c\", \"viz_unittests\": \"8bda38b05fc79db8cbca80ac96212ba15a3f19a8\", \"vr_common_unittests\": \"57a2ae76abb0ab0bab42982ab1f454e8ab5ab791\", \"vr_pixeltests\": \"2c1f9777572ecc8d3741ab11aaf16da9e21e2ca2\", \"weblayer_browsertests\": \"59da8fc582acecd1ecd59917af2b357d28c9ec2e\", \"weblayer_unittests\": \"7b2c3c33e40da01ffffe6a6bf477abcc15a72032\", \"wm_unittests\": \"8c7c94e45351ffc1738c5b4c291e54260aeeb396\", \"wtf_unittests\": \"92748534de8fb72855af0fd4ab5e7926b6303186\", \"zlib_unittests\": \"e5553eff791df2decc8f7026c12654aaaa409792\", \"zucchini_unittests\": \"84d957c3537bb5c8a9b4834f7e2c8e032ca6792d\"}}, \"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-7-2fjn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611979772382985",
"status": "COMPLETED",
"status_changed_ts": "1611983541458466",
"tags": [
"build_address:luci.chromium.ci/win-asan/17080",
"builder:win-asan",
"buildset:commit/git/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5aa8ab09d508d6745e7b7857c90f1421577155c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022140480726629360",
"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/8856710418603460912/+/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:516a4cce96396610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611983541462724",
"url": "https://ci.chromium.org/b/8856710418603460912",
"utcnow_ts": "1611983542909992"
}
}
[05:12:22.918] Invocation finished in 1h2m56.189434742s with status SUCCEEDED