[21:35:21.027] New invocation is queued and will start shortly
[21:35:22.359] Starting the invocation (attempt 1)
[21:35:22.395] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:35:22.395] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:35:22.395] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024883184051892624",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1105bcfce00f87c311b87ada0c42cf253c44c681\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024883184051892624",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1105bcfce00f87c311b87ada0c42cf253c44c681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1105bcfce00f87c311b87ada0c42cf253c44c681",
"gitiles_ref:refs/heads/master"
]
}
[21:35:23.135] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609364122523186",
"id": "8859453121819098112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1105bcfce00f87c311b87ada0c42cf253c44c681\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609364122896459",
"tags": [
"build_address:luci.chromium.ci/win-asan/16560",
"builder:win-asan",
"buildset:commit/git/1105bcfce00f87c311b87ada0c42cf253c44c681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1105bcfce00f87c311b87ada0c42cf253c44c681",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024883184051892624",
"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/8859453121819098112/+/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": "1609364122896568",
"url": "https://ci.chromium.org/b/8859453121819098112",
"utcnow_ts": "1609364123117446"
}
}
[21:35:23.135] Task URL: https://ci.chromium.org/b/8859453121819098112
[21:35:23.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:2:0) after 1m0s
[21:35:27.881] Received PubSub notification, asking Buildbucket for the build status
[21:35:28.121] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:23.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:2:0)
[21:36:23.170] Timer tick, asking Buildbucket for the build status
[21:36:23.341] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:23.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:4:0) after 1m0s
[21:37:23.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:4:0)
[21:37:23.346] Timer tick, asking Buildbucket for the build status
[21:37:23.603] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:23.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:5:0) after 1m0s
[21:38:23.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:5:0)
[21:38:23.710] Timer tick, asking Buildbucket for the build status
[21:38:23.800] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:23.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:6:0) after 1m0s
[21:39:23.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:6:0)
[21:39:23.869] Timer tick, asking Buildbucket for the build status
[21:39:24.003] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:24.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:7:0) after 1m0s
[21:40:24.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:7:0)
[21:40:24.052] Timer tick, asking Buildbucket for the build status
[21:40:24.189] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:24.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:8:0) after 1m0s
[21:41:24.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:8:0)
[21:41:24.195] Timer tick, asking Buildbucket for the build status
[21:41:24.362] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:24.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:9:0) after 1m0s
[21:42:24.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:9:0)
[21:42:24.386] Timer tick, asking Buildbucket for the build status
[21:42:24.452] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:24.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:10:0) after 1m0s
[21:43:24.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:10:0)
[21:43:24.523] Timer tick, asking Buildbucket for the build status
[21:43:24.616] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:24.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:11:0) after 1m0s
[21:44:24.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:11:0)
[21:44:24.830] Timer tick, asking Buildbucket for the build status
[21:44:24.886] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:24.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:12:0) after 1m0s
[21:45:24.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:12:0)
[21:45:24.886] Timer tick, asking Buildbucket for the build status
[21:45:24.983] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:24.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:13:0) after 1m0s
[21:46:25.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:13:0)
[21:46:25.034] Timer tick, asking Buildbucket for the build status
[21:46:25.122] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:25.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:14:0) after 1m0s
[21:47:25.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:14:0)
[21:47:25.146] Timer tick, asking Buildbucket for the build status
[21:47:25.415] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:25.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:15:0) after 1m0s
[21:48:25.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:15:0)
[21:48:25.457] Timer tick, asking Buildbucket for the build status
[21:48:25.636] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:16:0) after 1m0s
[21:49:25.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:16:0)
[21:49:25.653] Timer tick, asking Buildbucket for the build status
[21:49:25.729] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:25.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:17:0) after 1m0s
[21:50:25.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:17:0)
[21:50:25.749] Timer tick, asking Buildbucket for the build status
[21:50:26.069] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:26.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:18:0) after 1m0s
[21:51:26.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:18:0)
[21:51:26.122] Timer tick, asking Buildbucket for the build status
[21:51:26.267] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:26.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:19:0) after 1m0s
[21:52:26.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:19:0)
[21:52:26.319] Timer tick, asking Buildbucket for the build status
[21:52:26.422] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:26.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:20:0) after 1m0s
[21:53:26.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:20:0)
[21:53:26.444] Timer tick, asking Buildbucket for the build status
[21:53:26.684] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:26.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:21:0) after 1m0s
[21:54:26.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:21:0)
[21:54:26.791] Timer tick, asking Buildbucket for the build status
[21:54:26.909] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:26.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:22:0) after 1m0s
[21:55:26.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:22:0)
[21:55:26.973] Timer tick, asking Buildbucket for the build status
[21:55:27.251] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:27.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:23:0) after 1m0s
[21:56:27.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:23:0)
[21:56:27.304] Timer tick, asking Buildbucket for the build status
[21:56:27.429] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:24:0) after 1m0s
[21:57:27.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:24:0)
[21:57:27.451] Timer tick, asking Buildbucket for the build status
[21:57:27.552] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:27.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:25:0) after 1m0s
[21:58:27.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:25:0)
[21:58:27.607] Timer tick, asking Buildbucket for the build status
[21:58:27.703] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:27.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:26:0) after 1m0s
[21:59:27.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:26:0)
[21:59:27.753] Timer tick, asking Buildbucket for the build status
[21:59:27.890] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:27.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:27:0) after 1m0s
[22:00:27.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:27:0)
[22:00:27.889] Timer tick, asking Buildbucket for the build status
[22:00:28.073] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:28.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:28:0) after 1m0s
[22:01:28.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:28:0)
[22:01:28.117] Timer tick, asking Buildbucket for the build status
[22:01:28.240] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:28.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:29:0) after 1m0s
[22:02:28.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:29:0)
[22:02:28.261] Timer tick, asking Buildbucket for the build status
[22:02:28.372] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:28.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:30:0) after 1m0s
[22:03:28.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:30:0)
[22:03:28.428] Timer tick, asking Buildbucket for the build status
[22:03:28.502] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:28.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:31:0) after 1m0s
[22:04:28.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:31:0)
[22:04:28.521] Timer tick, asking Buildbucket for the build status
[22:04:28.588] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:28.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:32:0) after 1m0s
[22:05:28.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:32:0)
[22:05:28.612] Timer tick, asking Buildbucket for the build status
[22:05:28.741] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:28.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:33:0) after 1m0s
[22:06:28.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:33:0)
[22:06:28.735] Timer tick, asking Buildbucket for the build status
[22:06:28.903] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:28.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:34:0) after 1m0s
[22:07:28.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:34:0)
[22:07:28.923] Timer tick, asking Buildbucket for the build status
[22:07:29.094] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:29.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:35:0) after 1m0s
[22:08:29.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:35:0)
[22:08:29.139] Timer tick, asking Buildbucket for the build status
[22:08:29.213] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:29.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:36:0) after 1m0s
[22:09:29.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:36:0)
[22:09:29.309] Timer tick, asking Buildbucket for the build status
[22:09:29.618] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:29.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:37:0) after 1m0s
[22:10:29.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:37:0)
[22:10:29.638] Timer tick, asking Buildbucket for the build status
[22:10:29.760] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:29.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:38:0) after 1m0s
[22:11:29.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:38:0)
[22:11:29.801] Timer tick, asking Buildbucket for the build status
[22:11:30.301] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:30.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:39:0) after 1m0s
[22:12:30.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:39:0)
[22:12:30.405] Timer tick, asking Buildbucket for the build status
[22:12:30.788] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:30.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:40:0) after 1m0s
[22:13:30.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:40:0)
[22:13:30.821] Timer tick, asking Buildbucket for the build status
[22:13:30.937] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:30.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:41:0) after 1m0s
[22:14:30.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:41:0)
[22:14:30.983] Timer tick, asking Buildbucket for the build status
[22:14:31.071] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:31.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:42:0) after 1m0s
[22:15:31.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:42:0)
[22:15:31.146] Timer tick, asking Buildbucket for the build status
[22:15:31.255] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:31.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:43:0) after 1m0s
[22:16:31.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:43:0)
[22:16:31.295] Timer tick, asking Buildbucket for the build status
[22:16:31.590] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:31.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:44:0) after 1m0s
[22:17:31.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:44:0)
[22:17:31.652] Timer tick, asking Buildbucket for the build status
[22:17:31.775] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:31.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:45:0) after 1m0s
[22:18:31.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:45:0)
[22:18:31.890] Timer tick, asking Buildbucket for the build status
[22:18:32.007] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:32.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:46:0) after 1m0s
[22:19:32.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:46:0)
[22:19:32.069] Timer tick, asking Buildbucket for the build status
[22:19:32.181] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:32.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:47:0) after 1m0s
[22:20:32.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:47:0)
[22:20:32.274] Timer tick, asking Buildbucket for the build status
[22:20:32.408] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:32.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:48:0) after 1m0s
[22:21:32.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:48:0)
[22:21:32.420] Timer tick, asking Buildbucket for the build status
[22:21:32.697] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:32.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:49:0) after 1m0s
[22:22:32.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:49:0)
[22:22:32.731] Timer tick, asking Buildbucket for the build status
[22:22:32.853] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:32.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:50:0) after 1m0s
[22:23:32.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:50:0)
[22:23:32.838] Timer tick, asking Buildbucket for the build status
[22:23:32.919] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:32.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:51:0) after 1m0s
[22:24:32.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:51:0)
[22:24:32.960] Timer tick, asking Buildbucket for the build status
[22:24:33.032] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:33.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:52:0) after 1m0s
[22:25:33.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:52:0)
[22:25:33.126] Timer tick, asking Buildbucket for the build status
[22:25:33.250] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:33.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:53:0) after 1m0s
[22:26:33.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:53:0)
[22:26:33.236] Timer tick, asking Buildbucket for the build status
[22:26:33.486] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:33.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:54:0) after 1m0s
[22:27:33.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:54:0)
[22:27:33.590] Timer tick, asking Buildbucket for the build status
[22:27:33.685] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:55:0) after 1m0s
[22:28:33.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:55:0)
[22:28:33.708] Timer tick, asking Buildbucket for the build status
[22:28:33.787] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:33.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:56:0) after 1m0s
[22:29:33.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:56:0)
[22:29:33.858] Timer tick, asking Buildbucket for the build status
[22:29:33.953] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:33.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:57:0) after 1m0s
[22:30:33.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:57:0)
[22:30:33.981] Timer tick, asking Buildbucket for the build status
[22:30:34.286] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:34.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:58:0) after 1m0s
[22:31:34.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:58:0)
[22:31:34.308] Timer tick, asking Buildbucket for the build status
[22:31:34.881] Build 8859453121819098112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:34.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024883184051892624:59:0) after 1m0s
[22:31:38.759] Received PubSub notification, asking Buildbucket for the build status
[22:31:38.943] Build 8859453121819098112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:31:38.943] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609367497655449",
"created_by": "project:chromium",
"created_ts": "1609364122523186",
"id": "8859453121819098112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1105bcfce00f87c311b87ada0c42cf253c44c681\"}}",
"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\": \"1105bcfce00f87c311b87ada0c42cf253c44c681\", \"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-us-central1-b-32-1-2sgf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16560, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"938399865e720c2e37616cdad64109ef331bb035\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"1105bcfce00f87c311b87ada0c42cf253c44c681\", \"got_revision_cp\": \"refs/heads/master@{#839799}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"ef6c61213b14d3cf31e97bbd314e1dda24794a0d\", \"got_v8_revision_cp\": \"refs/heads/8.9.199@{#1}\", \"got_webrtc_revision\": \"f86cf4c2de2fb6fa37a2637b4613c37c9b75f48a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32890}\", \"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\": \"1105bcfce00f87c311b87ada0c42cf253c44c681\", \"swarm_hashes_refs/heads/master(at){#839799}_without_patch\": {\"absl_hardening_tests\": \"3ca82dbaabba65bac2cba0d03cfaec3dc2c31202\", \"accessibility_unittests\": \"4d8837848a202515bb7a18ba772047e5f7cf2875\", \"angle_unittests\": \"39633b6a117ed071d66aed213cb361fc84fbd497\", \"app_shell_unittests\": \"b68b573c1bcb4bf73b7b561a1112358c708a4560\", \"aura_unittests\": \"4afd17e2df2045968e0daae6f4baddde2cc0a804\", \"base_unittests\": \"937d73d48e1882e8fabb28cbe73badd20fdc45c6\", \"base_util_unittests\": \"1cdc7016aa67f7db15faf4afc6cd35c76f2d87e3\", \"blink_common_unittests\": \"6a367746b9af29a0e1bbe8d71ef41dd539776e72\", \"blink_fuzzer_unittests\": \"22741b7de53720c9f926d7e467fb4c3465e6d28b\", \"blink_heap_unittests\": \"53aee1fb4334e1eb87aba0d2fc942643eaad1f26\", \"blink_platform_unittests\": \"d00695af7e5b71345f3f14f63d127dea2a102ed3\", \"blink_unittests\": \"6e362d4acb2daac26d406e5bc2d162a0a6d8c648\", \"boringssl_crypto_tests\": \"7a30a92e8c1600f750e0f0c953f76839ce17c5b6\", \"boringssl_ssl_tests\": \"f39aa66eba77f3141a5a1aae04323814c055ba8f\", \"browser_switcher_bho_unittests\": \"65e5528c655665a92c69e44475d7b8df5be95ccd\", \"browser_tests\": \"2d4d3cb2d444604651550dfb097c2a5f3936362a\", \"capture_unittests\": \"7ec849c809cf84f88e495157fcd52bb198db1640\", \"cast_unittests\": \"84382fac1421913a8e3a4395a8d645e4f6ce30c4\", \"cc_unittests\": \"e4d38b1f8ee0cd44e62515fb31ec532220c16dd9\", \"chrome_app_unittests\": \"c32885a33aee1e8b316301eb73d6d0a5393dc3c4\", \"chrome_cleaner_unittests\": \"55f34d29aaa760e71fe89e1261897e87485f1b76\", \"chrome_elf_unittests\": \"6cbda5b95bc94fc2078ac2fb32bd505db940a173\", \"chromedriver_unittests\": \"15553930889f1a18cae16d91c79f0236846f76e3\", \"components_browsertests\": \"f88bfc22fcd99b085799df0aa3874154a3c4d4f6\", \"components_unittests\": \"4c7285cf25817eddf69fb2206e1873d60323cb59\", \"compositor_unittests\": \"bae176e023aa74b204387f69b2534ac9affa7c9e\", \"content_browsertests\": \"415bf2fb679fb593ad81a3141c79a2103f8ead3b\", \"content_unittests\": \"8f13f04dcf4a57d7434a925beb6b380c4c92edfa\", \"courgette_unittests\": \"1b51ca14e8a1ce0a89e692766794f1d2b7e11dd5\", \"crashpad_tests\": \"bd8dbd1ed9c418781e470d952c975ebbaa95bcef\", \"cronet_tests\": \"85b2bb84628ddd67716a5f6b8cf9a1dc7566152a\", \"cronet_unittests\": \"d5722268a9d06c3145387d43b84737c31af595f7\", \"crypto_unittests\": \"bfd899cacdc1ba9e35e38a0844284d3dac46f755\", \"delayloads_unittests\": \"b60a490c32566cfe457af04e3ee85627ebd7a344\", \"device_unittests\": \"2d12718472eb9e149d5cbd7186bb87a9c19ca176\", \"display_unittests\": \"0308c765ef87b43ba367c0311cbf301f966532e5\", \"elevation_service_unittests\": \"ed950a50ced52b580e237ac605707f9329239317\", \"events_unittests\": \"c076602d3b62c2ae88753fa5499e04dc3afce959\", \"extensions_browsertests\": \"d7bf27f573d8abce6d357cc49148d807cc76f1e8\", \"extensions_unittests\": \"e51eeb589d4932100a7f450435dc31ead6d514d1\", \"filesystem_service_unittests\": \"04193e33bf434a5c9b864fb3f270b516db11427f\", \"gcm_unit_tests\": \"88611f3c5f09b0213b4043f2f11c3c3d1bf353f9\", \"gcp_unittests\": \"82493ff9d3d1ed697187a8dacb1b4830293fa67a\", \"gfx_unittests\": \"c06ffebdde9cedb6fe54fdaee7446bf88717914b\", \"gin_unittests\": \"c6b4ea0a8d86759baa22b2e50afe45dfb3352260\", \"google_apis_unittests\": \"942c467bbd8805c5eb58dbd00f2c2f80aa25e2dc\", \"gpu_unittests\": \"5a853acf77aa2bec7cf9ba0d158aa2eb95eb9662\", \"gwp_asan_unittests\": \"0bf41fb37aa03105eb69a6661850bdb84796ad61\", \"headless_browsertests\": \"b8e8a77f5ba26f6c641a305389b2626208afe5f9\", \"headless_unittests\": \"ddc7fd93fe8d2f581a81cc054d2fc3ad990d2915\", \"install_static_unittests\": \"37c9c1e050cebe0124eb8b80d122d3622e5903f3\", \"installer_util_unittests\": \"a575ab78b046d24886a8b0e61474fa356096cdf7\", \"interactive_ui_tests\": \"a9c4dba18fb93af7d589c5253b3b92b0a05d7685\", \"ipc_tests\": \"703e7aad2a1d1d7069bc9a981c770575e887ac5b\", \"jingle_unittests\": \"645ff626207328205956183b751b4a1a41d7fbc8\", \"latency_unittests\": \"64cb76fe7bf1cfec9678e5b87bfbaaacbf55dbd7\", \"libjingle_xmpp_unittests\": \"1f1f22600ec4a8a5fd098bf9e97085d1b972e87e\", \"liburlpattern_unittests\": \"9ef69c8d10127418c5d86c59cc4bfb0079114634\", \"media_blink_unittests\": \"971965ede5b7195235ed0b43bba9915bdef763ec\", \"media_unittests\": \"57e818a3c815693b7884351fd5e5f5fc476e7054\", \"message_center_unittests\": \"20914b943e7abd887d4f3b6d850308a6cb1a7991\", \"midi_unittests\": \"1f23186c5023e9318867a5b2c305a85cf10f6a29\", \"mojo_core_unittests\": \"2cdceb1d9035231fa21918ec38a12b347605a8e9\", \"mojo_unittests\": \"b7933a96d55d6b7c9dcaa9400fb6f6fb285b1998\", \"nacl_loader_unittests\": \"ebc26a18a09236afce0ddbdbd22dce3e5531b9ec\", \"native_theme_unittests\": \"58b82bfb631d4d89babc3e4b41c08784a78d1ce8\", \"net_unittests\": \"b42caf7dba0d07fa605a49879b9e90b74c08d1ea\", \"notification_helper_unittests\": \"0abbb0760cdb272eb80eb9b2326dfeaedbb67de9\", \"pdf_unittests\": \"831f1770c35788fcc75db5c831e4062f19b96d1b\", \"perfetto_unittests\": \"0cdda5fb1714d9c963bb7b03b4fc73cf3a2e0123\", \"ppapi_unittests\": \"0b7befd5ab3d2d3cc895d716cd4b323b87cfe8eb\", \"printing_unittests\": \"6c158e6177a0e2d339097925ae6de22dd1e783c2\", \"remoting_unittests\": \"5eabdfe2bb214efa13bef4f6a63214bbc0febe07\", \"sbox_integration_tests\": \"b4cc33443f0ef416f54fdbf72215bf8f2c2511c8\", \"sbox_unittests\": \"2327f7e72bc2dcd8cde8f208da45308d13dd803c\", \"sbox_validation_tests\": \"2d9bf615dc9df4dc6916bb58abc3a06c0e0e34a3\", \"service_manager_unittests\": \"3c630bc979f9e9e52e7471fea2176c51283534ec\", \"services_unittests\": \"76937751b4ea38e627a58c4ca1d4f321b2c301ea\", \"setup_unittests\": \"563478396ebed0e1845db55a2bf3a0d46dd1b2ff\", \"shell_dialogs_unittests\": \"6e848c416db2565522416d884f142e0aeef29e61\", \"skia_unittests\": \"de32844b805f803e6c1f7457584601bb4680dfee\", \"snapshot_unittests\": \"2080e1fa0292fb45c401d06a5d53a2169ce0679b\", \"sql_unittests\": \"02cfd58ed75e20ef8385687ef0bce4d021d77a31\", \"storage_unittests\": \"a3cbc965f8f2f8ae61cecf6156e54eb28a41a2d7\", \"sync_integration_tests\": \"d1964770ed1605435da8bb935e85507845412fe8\", \"traffic_annotation_auditor_unittests\": \"711db2a359dc2a4b1f6f071ac7cc815bf112ccf4\", \"ui_base_unittests\": \"bdd8fcc0d6dc9dbf493d1999b08694f696578f86\", \"ui_touch_selection_unittests\": \"736bf4d8effadfad32422957218291240b658b73\", \"unit_tests\": \"f818fbcf8d0f41cac84a4cbf9fe8b69c2421b0d5\", \"updater_tests\": \"0897d2f72dff7ab07a1f89bc4856c615239da633\", \"url_unittests\": \"b9f9501c1ddbc70b8e8de58943143fe0cd47f116\", \"views_unittests\": \"71af0f65680d8bc2b8be1f2c5001c17278d35c46\", \"viz_unittests\": \"1af915c3c2bc16ce078a5cb873ac134e1a8c5445\", \"vr_common_unittests\": \"26de9a956cb7e1d527741a0cc9ce3b5698809762\", \"vr_pixeltests\": \"167e7cbe5de33d336ab005e96f9dc3947907ece8\", \"weblayer_browsertests\": \"d0afc7a47b52600465467d147c40689445d9e9e3\", \"weblayer_unittests\": \"8be69e5290638af641ba0a8736814639f0949328\", \"wm_unittests\": \"bd097674e0d360730c86c92cd2a8babffb2a4105\", \"wtf_unittests\": \"4323e050343cd98dd2d7001851aa3a4965d2668e\", \"zlib_unittests\": \"8d05b34c1745fd5be9008b0cc3048b4964142e04\", \"zucchini_unittests\": \"d3575d6467120e8f35e7adcf9a6b99d1da2fbc0b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609364127009817",
"status": "COMPLETED",
"status_changed_ts": "1609367498010944",
"tags": [
"build_address:luci.chromium.ci/win-asan/16560",
"builder:win-asan",
"buildset:commit/git/1105bcfce00f87c311b87ada0c42cf253c44c681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1105bcfce00f87c311b87ada0c42cf253c44c681",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024883184051892624",
"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/8859453121819098112/+/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:50ce65377e8c7c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609367498080194",
"url": "https://ci.chromium.org/b/8859453121819098112",
"utcnow_ts": "1609367498932367"
}
}
[22:31:38.943] Invocation finished in 56m17.931254552s with status SUCCEEDED