[17:06:23.346] New invocation is queued and will start shortly
[17:06:24.531] Starting the invocation (attempt 1)
[17:06:24.588] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:06:24.588] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:06:24.588] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026712044971650496",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ceadeb13196158d25acda3128ac633a76b9967fe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026712044971650496",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ceadeb13196158d25acda3128ac633a76b9967fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ceadeb13196158d25acda3128ac633a76b9967fe",
"gitiles_ref:refs/heads/master"
]
}
[17:06:25.515] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607619984734879",
"id": "8861281982845360896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ceadeb13196158d25acda3128ac633a76b9967fe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607619985281017",
"tags": [
"build_address:luci.chromium.ci/win-asan/16143",
"builder:win-asan",
"buildset:commit/git/ceadeb13196158d25acda3128ac633a76b9967fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ceadeb13196158d25acda3128ac633a76b9967fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026712044971650496",
"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/8861281982845360896/+/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": "1607619985281212",
"url": "https://ci.chromium.org/b/8861281982845360896",
"utcnow_ts": "1607619985501916"
}
}
[17:06:25.515] Task URL: https://ci.chromium.org/b/8861281982845360896
[17:06:25.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:2:0) after 1m0s
[17:06:28.969] Received PubSub notification, asking Buildbucket for the build status
[17:06:29.141] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:25.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:2:0)
[17:07:25.600] Timer tick, asking Buildbucket for the build status
[17:07:25.675] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:25.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:4:0) after 1m0s
[17:08:25.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:4:0)
[17:08:25.671] Timer tick, asking Buildbucket for the build status
[17:08:25.784] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:25.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:5:0) after 1m0s
[17:09:25.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:5:0)
[17:09:25.857] Timer tick, asking Buildbucket for the build status
[17:09:25.996] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:25.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:6:0) after 1m0s
[17:10:26.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:6:0)
[17:10:26.004] Timer tick, asking Buildbucket for the build status
[17:10:26.150] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:26.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:7:0) after 1m0s
[17:11:26.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:7:0)
[17:11:26.159] Timer tick, asking Buildbucket for the build status
[17:11:26.272] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:26.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:8:0) after 1m0s
[17:12:26.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:8:0)
[17:12:26.278] Timer tick, asking Buildbucket for the build status
[17:12:26.383] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:26.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:9:0) after 1m0s
[17:13:26.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:9:0)
[17:13:26.455] Timer tick, asking Buildbucket for the build status
[17:13:26.536] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:26.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:10:0) after 1m0s
[17:14:26.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:10:0)
[17:14:26.532] Timer tick, asking Buildbucket for the build status
[17:14:26.624] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:26.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:11:0) after 1m0s
[17:15:26.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:11:0)
[17:15:26.636] Timer tick, asking Buildbucket for the build status
[17:15:26.714] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:26.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:12:0) after 1m0s
[17:16:26.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:12:0)
[17:16:26.706] Timer tick, asking Buildbucket for the build status
[17:16:26.800] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:26.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:13:0) after 1m0s
[17:17:26.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:13:0)
[17:17:26.811] Timer tick, asking Buildbucket for the build status
[17:17:26.925] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:26.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:14:0) after 1m0s
[17:18:26.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:14:0)
[17:18:26.982] Timer tick, asking Buildbucket for the build status
[17:18:27.063] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:27.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:15:0) after 1m0s
[17:19:27.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:15:0)
[17:19:27.060] Timer tick, asking Buildbucket for the build status
[17:19:27.155] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:27.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:16:0) after 1m0s
[17:20:27.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:16:0)
[17:20:27.178] Timer tick, asking Buildbucket for the build status
[17:20:27.546] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:27.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:17:0) after 1m0s
[17:21:27.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:17:0)
[17:21:27.569] Timer tick, asking Buildbucket for the build status
[17:21:27.676] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:27.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:18:0) after 1m0s
[17:22:27.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:18:0)
[17:22:27.700] Timer tick, asking Buildbucket for the build status
[17:22:27.831] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:27.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:19:0) after 1m0s
[17:23:27.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:19:0)
[17:23:27.859] Timer tick, asking Buildbucket for the build status
[17:23:28.078] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:28.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:20:0) after 1m0s
[17:24:28.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:20:0)
[17:24:28.066] Timer tick, asking Buildbucket for the build status
[17:24:28.145] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:28.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:21:0) after 1m0s
[17:25:28.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:21:0)
[17:25:28.176] Timer tick, asking Buildbucket for the build status
[17:25:28.277] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:28.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:22:0) after 1m0s
[17:26:28.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:22:0)
[17:26:28.300] Timer tick, asking Buildbucket for the build status
[17:26:28.439] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:28.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:23:0) after 1m0s
[17:27:28.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:23:0)
[17:27:28.463] Timer tick, asking Buildbucket for the build status
[17:27:28.822] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:28.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:24:0) after 1m0s
[17:28:28.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:24:0)
[17:28:28.889] Timer tick, asking Buildbucket for the build status
[17:28:29.285] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:29.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:25:0) after 1m0s
[17:29:29.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:25:0)
[17:29:29.343] Timer tick, asking Buildbucket for the build status
[17:29:29.834] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:29.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:26:0) after 1m0s
[17:30:29.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:26:0)
[17:30:29.846] Timer tick, asking Buildbucket for the build status
[17:30:30.033] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:30.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:27:0) after 1m0s
[17:31:30.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:27:0)
[17:31:30.090] Timer tick, asking Buildbucket for the build status
[17:31:30.212] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:30.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:28:0) after 1m0s
[17:32:30.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:28:0)
[17:32:30.239] Timer tick, asking Buildbucket for the build status
[17:32:30.367] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:30.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:29:0) after 1m0s
[17:33:30.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:29:0)
[17:33:30.378] Timer tick, asking Buildbucket for the build status
[17:33:30.503] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:30.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:30:0) after 1m0s
[17:34:30.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:30:0)
[17:34:30.620] Timer tick, asking Buildbucket for the build status
[17:34:30.756] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:30.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:31:0) after 1m0s
[17:35:30.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:31:0)
[17:35:30.865] Timer tick, asking Buildbucket for the build status
[17:35:30.951] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:30.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:32:0) after 1m0s
[17:36:31.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:32:0)
[17:36:31.077] Timer tick, asking Buildbucket for the build status
[17:36:31.318] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:31.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:33:0) after 1m0s
[17:37:31.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:33:0)
[17:37:31.431] Timer tick, asking Buildbucket for the build status
[17:37:31.549] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:31.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:34:0) after 1m0s
[17:38:31.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:34:0)
[17:38:31.575] Timer tick, asking Buildbucket for the build status
[17:38:31.671] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:31.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:35:0) after 1m0s
[17:39:31.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:35:0)
[17:39:31.720] Timer tick, asking Buildbucket for the build status
[17:39:31.895] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:31.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:36:0) after 1m0s
[17:40:31.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:36:0)
[17:40:31.904] Timer tick, asking Buildbucket for the build status
[17:40:32.043] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:32.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:37:0) after 1m0s
[17:41:32.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:37:0)
[17:41:32.184] Timer tick, asking Buildbucket for the build status
[17:41:32.291] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:32.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:38:0) after 1m0s
[17:42:32.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:38:0)
[17:42:32.345] Timer tick, asking Buildbucket for the build status
[17:42:32.426] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:32.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:39:0) after 1m0s
[17:43:32.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:39:0)
[17:43:32.471] Timer tick, asking Buildbucket for the build status
[17:43:32.701] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:32.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:40:0) after 1m0s
[17:44:32.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:40:0)
[17:44:32.823] Timer tick, asking Buildbucket for the build status
[17:44:32.949] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:32.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:41:0) after 1m0s
[17:45:32.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:41:0)
[17:45:32.995] Timer tick, asking Buildbucket for the build status
[17:45:33.154] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:33.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:42:0) after 1m0s
[17:46:33.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:42:0)
[17:46:33.214] Timer tick, asking Buildbucket for the build status
[17:46:33.392] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:33.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:43:0) after 1m0s
[17:47:33.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:43:0)
[17:47:33.389] Timer tick, asking Buildbucket for the build status
[17:47:33.625] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:33.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:44:0) after 1m0s
[17:48:33.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:44:0)
[17:48:33.743] Timer tick, asking Buildbucket for the build status
[17:48:34.191] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:34.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:45:0) after 1m0s
[17:49:34.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:45:0)
[17:49:34.206] Timer tick, asking Buildbucket for the build status
[17:49:34.559] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:34.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:46:0) after 1m0s
[17:50:34.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:46:0)
[17:50:34.595] Timer tick, asking Buildbucket for the build status
[17:50:34.934] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:34.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:47:0) after 1m0s
[17:51:35.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:47:0)
[17:51:35.005] Timer tick, asking Buildbucket for the build status
[17:51:35.149] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:35.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:48:0) after 1m0s
[17:52:35.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:48:0)
[17:52:35.201] Timer tick, asking Buildbucket for the build status
[17:52:37.776] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:37.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:49:0) after 1m0s
[17:53:37.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:49:0)
[17:53:37.750] Timer tick, asking Buildbucket for the build status
[17:53:38.763] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:38.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:50:0) after 1m0s
[17:54:38.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:50:0)
[17:54:38.768] Timer tick, asking Buildbucket for the build status
[17:54:39.310] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:39.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:51:0) after 1m0s
[17:55:39.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:51:0)
[17:55:39.337] Timer tick, asking Buildbucket for the build status
[17:55:39.410] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:52:0) after 1m0s
[17:56:39.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:52:0)
[17:56:39.437] Timer tick, asking Buildbucket for the build status
[17:56:39.578] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:53:0) after 1m0s
[17:57:39.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:53:0)
[17:57:39.598] Timer tick, asking Buildbucket for the build status
[17:57:39.675] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:39.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:54:0) after 1m0s
[17:58:39.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:54:0)
[17:58:39.676] Timer tick, asking Buildbucket for the build status
[17:58:39.769] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:39.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:55:0) after 1m0s
[17:59:39.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:55:0)
[17:59:39.795] Timer tick, asking Buildbucket for the build status
[17:59:39.931] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:39.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:56:0) after 1m0s
[18:00:40.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:56:0)
[18:00:40.019] Timer tick, asking Buildbucket for the build status
[18:00:40.132] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:40.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:57:0) after 1m0s
[18:01:40.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:57:0)
[18:01:40.305] Timer tick, asking Buildbucket for the build status
[18:01:40.431] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:40.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:58:0) after 1m0s
[18:02:40.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:58:0)
[18:02:40.561] Timer tick, asking Buildbucket for the build status
[18:02:40.655] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:40.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:59:0) after 1m0s
[18:03:40.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:59:0)
[18:03:40.713] Timer tick, asking Buildbucket for the build status
[18:03:40.778] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:40.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:60:0) after 1m0s
[18:04:40.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:60:0)
[18:04:40.888] Timer tick, asking Buildbucket for the build status
[18:04:40.968] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:40.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:61:0) after 1m0s
[18:05:41.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:61:0)
[18:05:41.067] Timer tick, asking Buildbucket for the build status
[18:05:41.859] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:41.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:62:0) after 1m0s
[18:06:41.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:62:0)
[18:06:41.875] Timer tick, asking Buildbucket for the build status
[18:06:42.408] Build 8861281982845360896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:42.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026712044971650496:63:0) after 1m0s
[18:06:51.714] Received PubSub notification, asking Buildbucket for the build status
[18:06:51.803] Build 8861281982845360896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:06:51.803] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607623606905046",
"created_by": "project:chromium",
"created_ts": "1607619984734879",
"id": "8861281982845360896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ceadeb13196158d25acda3128ac633a76b9967fe\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"ceadeb13196158d25acda3128ac633a76b9967fe\", \"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-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16143, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"32f1a8e812174be4507c4ab574bc3d14c1ba9a02\", \"got_dawn_revision\": \"29fd62989338a0d5812bc654a3afee6dc684bfc4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"ceadeb13196158d25acda3128ac633a76b9967fe\", \"got_revision_cp\": \"refs/heads/master@{#835700}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"d94a77b304f4fcb503ea037be3e5d7f2a371c4b6\", \"got_v8_revision\": \"5aee2a07f81fd05878de79fc84367615dc246942\", \"got_v8_revision_cp\": \"refs/heads/8.9.127@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"ceadeb13196158d25acda3128ac633a76b9967fe\", \"swarm_hashes_refs/heads/master(at){#835700}_without_patch\": {\"absl_hardening_tests\": \"10cb0d9cd3a969fb22162fa7a843d5a1b4368278\", \"accessibility_unittests\": \"96f5b00fd73ac9d5325304bfe488064858a3e3f2\", \"angle_unittests\": \"78fab95bf5f0c72d945612b47d33f9c4422746c5\", \"app_shell_unittests\": \"6fa89304a3810d27decb42fec96cd6c656907da7\", \"aura_unittests\": \"a8e04dba8b4d7ccde960836df76bd2953dde50aa\", \"base_unittests\": \"df7227bf66d93de38a4bf9ccfc5fab075d9562f9\", \"base_util_unittests\": \"75939606105ac499ebc7ce29068385ef0acb21dc\", \"blink_common_unittests\": \"4cddb058af83a0c0474a766e7c364deee51643c4\", \"blink_fuzzer_unittests\": \"725340ec5e2e6eecdb0ce8fc81e3a46c59e23758\", \"blink_heap_unittests\": \"09a0e9e918650eeab9dcf70b0c37fb476077131c\", \"blink_platform_unittests\": \"d4318a53958b559ef859e00694045775a3dbd571\", \"blink_unittests\": \"2b1deae1d290e74e4b8399b6680b34c6810eac1d\", \"boringssl_crypto_tests\": \"ab1a1e3e7f334c5b1bfbde35e2067eecfc7a7bbd\", \"boringssl_ssl_tests\": \"764ba4dc3929ab9d7905c283024963e9ee0fc975\", \"browser_switcher_bho_unittests\": \"f7170bdb9a823c80b0d3025e816a41026a9ac21d\", \"browser_tests\": \"453e3eb076040a0dbcb4c40a02b0335a3120e50e\", \"capture_unittests\": \"5abea227256bee56a95500b4184de8acf17f1505\", \"cast_unittests\": \"764f0d880b9f7a3d7be17368fc1616bf5d9b7e39\", \"cc_unittests\": \"da7f59dee4a86b9776f1ad1981072898ab195f22\", \"chrome_app_unittests\": \"0f94dcaab8664bc52d7c8b049d796208afd43fbd\", \"chrome_cleaner_unittests\": \"69b08c8bd9c9e5c9beea0391d26c3a39f6f664d8\", \"chrome_elf_unittests\": \"8366923ed9d6bd2c7d2f833647395da90d37d16e\", \"chromedriver_unittests\": \"52e58e221a6cf208f95b1329ce398794a25a3a1c\", \"components_browsertests\": \"35f5ce3a057195a98386de8f587daf5e418adfbe\", \"components_unittests\": \"a1fc9968d037156459ddb13933e710a6b03af911\", \"compositor_unittests\": \"91b1cfed45a40a90827097c86f614431b7802d02\", \"content_browsertests\": \"cb962698e9885e57c7f9ae9d4cf3e1c0328c4aa6\", \"content_unittests\": \"ae48425732efc5b07ca6cc7187a82d6826055ab9\", \"courgette_unittests\": \"5ef7f8571d89f2749c8640d63c0748b0d03d1fbd\", \"crashpad_tests\": \"19f4fa8f74370bd938cf7620c86469faaeb59397\", \"cronet_tests\": \"57ce8c8a5343ca0b5bcd68054083fd86fc007649\", \"cronet_unittests\": \"0ea42cc4bf781d159fb8650927a6da57c22c3c36\", \"crypto_unittests\": \"b3659c7f9329ae23b26e54a8a4d7ef9cd200b3d4\", \"delayloads_unittests\": \"a227849aa57af304417dda3d6e1ed364d73c6668\", \"device_unittests\": \"f0c608875ef7c9798955e3a971b8321c0da5e559\", \"display_unittests\": \"4843d2f166f59e242e61ce56abc575775a0b8d2c\", \"elevation_service_unittests\": \"dc505b7b9e2ec321eaa0a38b1fbfc2c021a40b2a\", \"events_unittests\": \"6509da1d2f06c0fd2556821671a45c6d427aab73\", \"extensions_browsertests\": \"a6c33d0d0a8f5501918a02e029640cf3d364ee98\", \"extensions_unittests\": \"e93e7cf97bed908a0833abba51f621766f09e146\", \"filesystem_service_unittests\": \"a60d082b2002d474dc9651469379c5672e640964\", \"gcm_unit_tests\": \"b92d8bec73525a65c04b7480132ccfdbb991d196\", \"gcp_unittests\": \"2253d26bfea57c160da62c6700a777155284a6ef\", \"gfx_unittests\": \"00577d9abc32ad41fc2af3b092bf30e7c75a924c\", \"gin_unittests\": \"22cf701ef44b9be04d5f66bb32e9624c3ce6e600\", \"google_apis_unittests\": \"09082504590c7285c8a6302b4005efbb79ba1601\", \"gpu_unittests\": \"d8ce791effb2e05b4d69df0b2c7f97977dd61f39\", \"gwp_asan_unittests\": \"04c44bf29df0cab4f8f68cee76cb0e556084ed7b\", \"headless_browsertests\": \"08f394cd5fbbe6ac7636d356bd36fb38d068b417\", \"headless_unittests\": \"9ecaf3f6b50d9bb8d94a4e11ef4d630e5260b2f2\", \"install_static_unittests\": \"5bf5a03bffbc79652de4829959e003794d5c5261\", \"installer_util_unittests\": \"55107623e6bef1d6d0a22aabf94c711ba70cfb98\", \"interactive_ui_tests\": \"0591c68042b4a0dfe5ed7d0b2765549d31ad8620\", \"ipc_tests\": \"d4727f9bb22edaa6f1fa398a1f0c70fce7430490\", \"jingle_unittests\": \"5de8af739ed44760bb4b956a6715e46b8b8ca63d\", \"latency_unittests\": \"2c72777ac74803f26adfeaabe196a115d123f8ac\", \"libjingle_xmpp_unittests\": \"5770ea6e91f32713dc759570dcb26a77a17c1a89\", \"liburlpattern_unittests\": \"64e12104afff5442e7b1593975b3de1e99cf70f6\", \"media_blink_unittests\": \"054d60aa32eae4f93b25c53608084c8f6464866a\", \"media_unittests\": \"d9cc4946ee7ef49c222edd7cb5e839777dbcd338\", \"message_center_unittests\": \"04fc5803a4c74b728a4354f9e65eede753705b86\", \"midi_unittests\": \"9e5af0c6e76cb4ed3c60734fdb8766244e667b68\", \"mojo_core_unittests\": \"14c90b1ed1946cd34fa7d9a4be3b9e397dd51198\", \"mojo_unittests\": \"1d8401647c89bb5f5d7a4ec193d80aa914ab68a8\", \"nacl_loader_unittests\": \"08fef0e7e1fa03d406e7b936baffd2a4be18f67a\", \"native_theme_unittests\": \"56658aac98101cec6e4d766142c56c261a4f13ab\", \"net_unittests\": \"e1932eadf0042e7bf6709804ef0c16767b5145c2\", \"notification_helper_unittests\": \"1ca18f219a6d5131afff8a5e7f9414f5214e6304\", \"pdf_unittests\": \"eb40e7f60ee0b4316cefaabe0ee49aabefa6a84a\", \"perfetto_unittests\": \"05a0faf7a76539061bc4e308fcd60392c5e0466e\", \"ppapi_unittests\": \"eb74c19d421887535b6d3cbbc8135eb8cdee7fcc\", \"printing_unittests\": \"d56fc0e6b480dd34dc3d5e7d09971f1addb3d5ed\", \"remoting_unittests\": \"8e873d95cc65be74088c3d57adee6bbcac12f7b7\", \"sbox_integration_tests\": \"235677d6689da7eef708171065db541920e94221\", \"sbox_unittests\": \"b6ce798064d254f9c5d3cee5505ac214feaf4b0e\", \"sbox_validation_tests\": \"1b72e35e3c8d7d5252f424e896dbb1fc4f70b467\", \"service_manager_unittests\": \"3f67f29799bdff1fb729052226c76f673d20bff0\", \"services_unittests\": \"83b9690a26de489a1ef5cdb3c759581a21bf5e45\", \"setup_unittests\": \"687975eb0c97b3fc8b39e8b19287c0d0c9a91e53\", \"shell_dialogs_unittests\": \"a613332e2ef9cd8e3dcf234fb2ec54ffff04d179\", \"skia_unittests\": \"2a000f1cc05031a97adcc37a8a6317cf029e05e3\", \"snapshot_unittests\": \"eb8dd791c9613da5c401fb2588f1101bbbd456cb\", \"sql_unittests\": \"aff24703dd5684be6b5838bba4f75e747b2741b8\", \"storage_unittests\": \"8013924a6b79530bb2ff88661813682e625ad780\", \"sync_integration_tests\": \"b7927a86f21d88d461238432dd6d941cc9f27831\", \"traffic_annotation_auditor_unittests\": \"fe7e3566bc02a4d9cd56b8ee35e580e969876046\", \"ui_base_unittests\": \"4161a22a21a46ebfc1c8b7a749fe5492d594a8dd\", \"ui_touch_selection_unittests\": \"4f5abe101ab9f9ff3fd2ee332b1331e1d270589c\", \"unit_tests\": \"bff32ca95fd41184cb4fbcea7c503d65d6ce8081\", \"updater_tests\": \"029033e51ecf9262c03fc9cf18533f333539511b\", \"url_unittests\": \"d56fdaab6c8b255bbbc700d234650e20a777158d\", \"views_unittests\": \"b923935278b2a9d6d739df9d5533bd8297b6df52\", \"viz_unittests\": \"683b0fa1c060392ed83a9c18023f02e312255a6f\", \"vr_common_unittests\": \"f7245fc3216b4c8ccb63958d31c3c3048d2dc5f7\", \"vr_pixeltests\": \"f38da8621c75839e8a9e1a4ece43491853b483d4\", \"weblayer_browsertests\": \"5b999f21d64dc9fbf370b0709082212f91aafe8c\", \"weblayer_unittests\": \"2062c02f33e7c6bd8493bd01c16d5e2290792104\", \"wm_unittests\": \"4b1e941a331e442287ee74497b298c80af2cdf97\", \"wtf_unittests\": \"53def9625f6add028e4a229cf6b1b11284cbe765\", \"zlib_unittests\": \"524e025f64c2d1fb6720bd29b409750157610dff\", \"zucchini_unittests\": \"c5c4bee6c7ddfad2cc0f3b54703abc7d326eaff9\"}}, \"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-6-3vmi\"], \"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\": [\"5657-0417a3f\"], \"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": "1607619987740073",
"status": "COMPLETED",
"status_changed_ts": "1607623607627814",
"tags": [
"build_address:luci.chromium.ci/win-asan/16143",
"builder:win-asan",
"buildset:commit/git/ceadeb13196158d25acda3128ac633a76b9967fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ceadeb13196158d25acda3128ac633a76b9967fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026712044971650496",
"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/8861281982845360896/+/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:50666fc962938c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607623607650027",
"url": "https://ci.chromium.org/b/8861281982845360896",
"utcnow_ts": "1607623611793060"
}
}
[18:06:51.803] Invocation finished in 1h0m28.479548221s with status SUCCEEDED