[00:16:52.586] New invocation is queued and will start shortly
[00:16:53.916] Starting the invocation (attempt 1)
[00:16:53.957] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:16:53.957] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:16:53.957] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028859288235859008",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028859288235859008",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"gitiles_ref:refs/heads/master"
]
}
[00:16:55.924] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605572214078386",
"id": "8863429226009054160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605572215500970",
"tags": [
"build_address:luci.chromium.ci/win-asan/15676",
"builder:win-asan",
"buildset:commit/git/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028859288235859008",
"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/8863429226009054160/+/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": "1605572215501135",
"url": "https://ci.chromium.org/b/8863429226009054160",
"utcnow_ts": "1605572215909602"
}
}
[00:16:55.924] Task URL: https://ci.chromium.org/b/8863429226009054160
[00:16:55.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:2:0) after 1m0s
[00:17:01.305] Received PubSub notification, asking Buildbucket for the build status
[00:17:01.404] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:2:0)
[00:17:56.139] Timer tick, asking Buildbucket for the build status
[00:17:56.396] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:4:0) after 1m0s
[00:18:56.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:4:0)
[00:18:56.537] Timer tick, asking Buildbucket for the build status
[00:18:56.666] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:56.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:5:0) after 1m0s
[00:19:56.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:5:0)
[00:19:56.686] Timer tick, asking Buildbucket for the build status
[00:19:56.830] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:56.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:6:0) after 1m0s
[00:20:56.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:6:0)
[00:20:56.886] Timer tick, asking Buildbucket for the build status
[00:20:57.020] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:57.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:7:0) after 1m0s
[00:21:57.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:7:0)
[00:21:57.107] Timer tick, asking Buildbucket for the build status
[00:21:57.407] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:57.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:8:0) after 1m0s
[00:22:57.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:8:0)
[00:22:57.431] Timer tick, asking Buildbucket for the build status
[00:22:57.486] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:57.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:9:0) after 1m0s
[00:23:57.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:9:0)
[00:23:57.523] Timer tick, asking Buildbucket for the build status
[00:23:57.589] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:57.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:10:0) after 1m0s
[00:24:57.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:10:0)
[00:24:57.672] Timer tick, asking Buildbucket for the build status
[00:24:57.764] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:57.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:11:0) after 1m0s
[00:25:57.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:11:0)
[00:25:57.778] Timer tick, asking Buildbucket for the build status
[00:25:57.844] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:12:0) after 1m0s
[00:26:57.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:12:0)
[00:26:57.890] Timer tick, asking Buildbucket for the build status
[00:26:57.990] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:57.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:13:0) after 1m0s
[00:27:57.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:13:0)
[00:27:57.993] Timer tick, asking Buildbucket for the build status
[00:27:58.353] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:58.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:14:0) after 1m0s
[00:28:58.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:14:0)
[00:28:58.401] Timer tick, asking Buildbucket for the build status
[00:28:58.603] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:58.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:15:0) after 1m0s
[00:29:58.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:15:0)
[00:29:58.632] Timer tick, asking Buildbucket for the build status
[00:29:58.701] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:58.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:16:0) after 1m0s
[00:30:58.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:16:0)
[00:30:58.687] Timer tick, asking Buildbucket for the build status
[00:30:58.774] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:58.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:17:0) after 1m0s
[00:31:58.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:17:0)
[00:31:58.860] Timer tick, asking Buildbucket for the build status
[00:31:58.969] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:58.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:18:0) after 1m0s
[00:32:59.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:18:0)
[00:32:59.081] Timer tick, asking Buildbucket for the build status
[00:32:59.194] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:59.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:19:0) after 1m0s
[00:33:59.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:19:0)
[00:33:59.217] Timer tick, asking Buildbucket for the build status
[00:33:59.348] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:59.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:20:0) after 1m0s
[00:34:59.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:20:0)
[00:34:59.374] Timer tick, asking Buildbucket for the build status
[00:34:59.494] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:59.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:21:0) after 1m0s
[00:35:59.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:21:0)
[00:35:59.568] Timer tick, asking Buildbucket for the build status
[00:35:59.904] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:59.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:22:0) after 1m0s
[00:36:59.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:22:0)
[00:36:59.931] Timer tick, asking Buildbucket for the build status
[00:37:00.757] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:00.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:23:0) after 1m0s
[00:38:01.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:23:0)
[00:38:01.059] Timer tick, asking Buildbucket for the build status
[00:38:01.201] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:01.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:24:0) after 1m0s
[00:39:01.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:24:0)
[00:39:01.224] Timer tick, asking Buildbucket for the build status
[00:39:01.313] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:01.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:25:0) after 1m0s
[00:40:01.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:25:0)
[00:40:01.362] Timer tick, asking Buildbucket for the build status
[00:40:01.506] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:01.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:26:0) after 1m0s
[00:41:01.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:26:0)
[00:41:01.560] Timer tick, asking Buildbucket for the build status
[00:41:01.851] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:01.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:27:0) after 1m0s
[00:42:01.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:27:0)
[00:42:01.871] Timer tick, asking Buildbucket for the build status
[00:42:02.232] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:02.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:28:0) after 1m0s
[00:43:02.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:28:0)
[00:43:02.332] Timer tick, asking Buildbucket for the build status
[00:43:02.471] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:02.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:29:0) after 1m0s
[00:44:02.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:29:0)
[00:44:02.639] Timer tick, asking Buildbucket for the build status
[00:44:02.771] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:02.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:30:0) after 1m0s
[00:45:02.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:30:0)
[00:45:02.877] Timer tick, asking Buildbucket for the build status
[00:45:03.119] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:03.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:31:0) after 1m0s
[00:46:03.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:31:0)
[00:46:03.127] Timer tick, asking Buildbucket for the build status
[00:46:03.522] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:03.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:32:0) after 1m0s
[00:47:03.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:32:0)
[00:47:03.533] Timer tick, asking Buildbucket for the build status
[00:47:03.714] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:33:0) after 1m0s
[00:48:03.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:33:0)
[00:48:03.764] Timer tick, asking Buildbucket for the build status
[00:48:04.228] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:04.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:34:0) after 1m0s
[00:49:04.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:34:0)
[00:49:04.314] Timer tick, asking Buildbucket for the build status
[00:49:04.668] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:04.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:35:0) after 1m0s
[00:50:04.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:35:0)
[00:50:04.810] Timer tick, asking Buildbucket for the build status
[00:50:04.920] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:36:0) after 1m0s
[00:51:05.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:36:0)
[00:51:05.028] Timer tick, asking Buildbucket for the build status
[00:51:05.298] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:05.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:37:0) after 1m0s
[00:52:05.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:37:0)
[00:52:05.351] Timer tick, asking Buildbucket for the build status
[00:52:05.469] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:05.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:38:0) after 1m0s
[00:53:05.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:38:0)
[00:53:05.527] Timer tick, asking Buildbucket for the build status
[00:53:05.768] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:05.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:39:0) after 1m0s
[00:54:05.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:39:0)
[00:54:05.789] Timer tick, asking Buildbucket for the build status
[00:54:05.918] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:05.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:40:0) after 1m0s
[00:55:05.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:40:0)
[00:55:05.957] Timer tick, asking Buildbucket for the build status
[00:55:06.693] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:06.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:41:0) after 1m0s
[00:56:06.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:41:0)
[00:56:06.734] Timer tick, asking Buildbucket for the build status
[00:56:06.850] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:06.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:42:0) after 1m0s
[00:57:06.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:42:0)
[00:57:06.934] Timer tick, asking Buildbucket for the build status
[00:57:10.110] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:10.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:43:0) after 1m0s
[00:58:10.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:43:0)
[00:58:10.189] Timer tick, asking Buildbucket for the build status
[00:58:10.271] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:10.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:44:0) after 1m0s
[00:59:10.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:44:0)
[00:59:10.335] Timer tick, asking Buildbucket for the build status
[00:59:10.446] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:10.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:45:0) after 1m0s
[01:00:10.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:45:0)
[01:00:10.494] Timer tick, asking Buildbucket for the build status
[01:00:15.488] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:15.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:46:0) after 1m0s
[01:01:15.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:46:0)
[01:01:15.489] Timer tick, asking Buildbucket for the build status
[01:01:15.565] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:15.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:47:0) after 1m0s
[01:02:15.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:47:0)
[01:02:15.610] Timer tick, asking Buildbucket for the build status
[01:02:17.621] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:17.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:48:0) after 1m0s
[01:03:17.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:48:0)
[01:03:17.623] Timer tick, asking Buildbucket for the build status
[01:03:18.571] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:18.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:49:0) after 1m0s
[01:04:18.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:49:0)
[01:04:18.667] Timer tick, asking Buildbucket for the build status
[01:04:18.760] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:18.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:50:0) after 1m0s
[01:05:18.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:50:0)
[01:05:18.825] Timer tick, asking Buildbucket for the build status
[01:05:18.936] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:18.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:51:0) after 1m0s
[01:06:18.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:51:0)
[01:06:18.938] Timer tick, asking Buildbucket for the build status
[01:06:19.128] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:19.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:52:0) after 1m0s
[01:07:19.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:52:0)
[01:07:19.170] Timer tick, asking Buildbucket for the build status
[01:07:19.713] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:19.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:53:0) after 1m0s
[01:08:19.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:53:0)
[01:08:19.758] Timer tick, asking Buildbucket for the build status
[01:08:20.283] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:20.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:54:0) after 1m0s
[01:09:20.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:54:0)
[01:09:20.364] Timer tick, asking Buildbucket for the build status
[01:09:20.482] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:20.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:55:0) after 1m0s
[01:10:20.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:55:0)
[01:10:20.543] Timer tick, asking Buildbucket for the build status
[01:10:20.611] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:20.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:56:0) after 1m0s
[01:11:20.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:56:0)
[01:11:20.649] Timer tick, asking Buildbucket for the build status
[01:11:20.984] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:20.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:57:0) after 1m0s
[01:12:21.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:57:0)
[01:12:21.075] Timer tick, asking Buildbucket for the build status
[01:12:21.219] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:21.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:58:0) after 1m0s
[01:13:21.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:58:0)
[01:13:21.332] Timer tick, asking Buildbucket for the build status
[01:13:21.404] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:21.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:59:0) after 1m0s
[01:14:21.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:59:0)
[01:14:21.497] Timer tick, asking Buildbucket for the build status
[01:14:21.563] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:60:0) after 1m0s
[01:15:21.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:60:0)
[01:15:21.694] Timer tick, asking Buildbucket for the build status
[01:15:22.131] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:22.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:61:0) after 1m0s
[01:16:22.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:61:0)
[01:16:22.213] Timer tick, asking Buildbucket for the build status
[01:16:22.436] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:22.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:62:0) after 1m0s
[01:17:22.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:62:0)
[01:17:22.471] Timer tick, asking Buildbucket for the build status
[01:17:22.621] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:22.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:63:0) after 1m0s
[01:18:22.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:63:0)
[01:18:22.709] Timer tick, asking Buildbucket for the build status
[01:18:22.935] Build 8863429226009054160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:22.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028859288235859008:64:0) after 1m0s
[01:18:39.281] Received PubSub notification, asking Buildbucket for the build status
[01:18:39.364] Build 8863429226009054160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:18:39.364] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605575916518472",
"created_by": "project:chromium",
"created_ts": "1605572214078386",
"id": "8863429226009054160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\"}}",
"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\": \"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\", \"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-g9w4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15676, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"620cb770e3dd506be78103e362cfdab96fd2dca4\", \"got_dawn_revision\": \"2c5b040b5c2aa9482c7dc6a8dcbc730db21d5222\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\", \"got_revision_cp\": \"refs/heads/master@{#827991}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"3e551b3ce67ddeaf0a29516b4dc98375886daf51\", \"got_v8_revision\": \"e05d24f98144a68f3f5d84b1dc1a9a28a0c7c804\", \"got_v8_revision_cp\": \"refs/heads/8.9.2@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc\", \"swarm_hashes_refs/heads/master(at){#827991}_without_patch\": {\"absl_hardening_tests\": \"a773d1a8c0826bea6668939a026fdfb5936076a1\", \"accessibility_unittests\": \"9c600d23721a5d4ee7d53b3db1d9a1c6eb5dab86\", \"angle_unittests\": \"9d1b0e6cd6a016395c384dab6441559653e0e30c\", \"app_shell_unittests\": \"f231589adb5d87394db65992945f74b0ef429b38\", \"aura_unittests\": \"c8c03f7143551d521e524f4d6b1efc65316fdc3e\", \"base_unittests\": \"88c1e95a523ecbd638d6ffb275a74351249f9a2c\", \"base_util_unittests\": \"d2238bf29b044fd38d08055f5da18314f31d10ae\", \"blink_common_unittests\": \"b6083bef10af348a08bb8f84f8e640a959523ee6\", \"blink_fuzzer_unittests\": \"83a037f1699bcdb502ef0dac0a45ddefad5676f4\", \"blink_heap_unittests\": \"e162b699384f1dd6bdc95016331709fba0e5e7c6\", \"blink_platform_unittests\": \"787df8ec4b99a4997d4ca9d4462ecb7a0918d649\", \"blink_unittests\": \"d3687af01d97fd55f9e40001248e601790d134e9\", \"boringssl_crypto_tests\": \"b3ec7a9c89837c757dcaeda67df9ddd679c23bc3\", \"boringssl_ssl_tests\": \"1c79f91b92d43673f2b6199c3ed306187daca15f\", \"browser_switcher_bho_unittests\": \"5b1644446c8d6b8033ad407b3eee665b36d8c7b6\", \"browser_tests\": \"e446f30babad1ebfbf422898a367b97686d69c5d\", \"capture_unittests\": \"a6b962a1896aca3cf528eb950504e3eb0021254a\", \"cast_unittests\": \"eb27ea59f3d4b0d6c104b4e3c513e9bd7d662a59\", \"cc_unittests\": \"fa625898d64d437e8822b8154c138da00c54741b\", \"chrome_app_unittests\": \"70290fab0aa7d850d15dcd315a56bf599d050332\", \"chrome_cleaner_unittests\": \"11355a89a3fc0157531df4764e013993e0cbfc32\", \"chrome_elf_unittests\": \"0aac8ca856b829f7f07c8af90bcb09e567cd618d\", \"chromedriver_unittests\": \"250c0ed6c158be39fb11f51e13b39356f6c048fa\", \"components_browsertests\": \"68e759d4ac9e8183d362799e2177f707cb09d4da\", \"components_unittests\": \"fdab65c6006e8c35018216fcec49e96f43cbc8ff\", \"compositor_unittests\": \"58b3b4f08a9e8896f0db693ee33d5365c688ef7f\", \"content_browsertests\": \"e2624f911316c4e1f63e0e315e14dba692f32ed9\", \"content_unittests\": \"39555bb9e2d2f71cf2708d9cda72a6c14deeb824\", \"courgette_unittests\": \"9c9d2192c3c30d2131c9fd361f0f3b4e553a1a71\", \"crashpad_tests\": \"1574292296c1c3aa085ed2b46952c0e4a96693eb\", \"cronet_tests\": \"e4ae647fd744372818adefc69447b9cf035efe78\", \"cronet_unittests\": \"de30e35ef62beb2abdd82c883e289cbd24ab6827\", \"crypto_unittests\": \"740452c1c56c0da4a56dcf2936499620d4c2b4ac\", \"delayloads_unittests\": \"50ea807c2f41c9f3727c99ce4974e4d7faa414b2\", \"device_unittests\": \"9b149e8b3ab807609a64226d0815b38404b60acb\", \"display_unittests\": \"60db955d59b252814980288efbff61988a16b72e\", \"elevation_service_unittests\": \"50f30d992f0b4bb681d4556b1d321f4188f5ca41\", \"events_unittests\": \"d209c8b08671a52abbe986da2ffec6e1caf1e040\", \"extensions_browsertests\": \"e8ab54bec2677001ca4177dc0219599bcf84a5b4\", \"extensions_unittests\": \"abc0ba6f727335a485048aba620162157ec64eaa\", \"filesystem_service_unittests\": \"feed281f558535d8aaf28ee47442c855cb1ce85e\", \"gcm_unit_tests\": \"94311b6fbaf76301112777e7af3b51b203cd7687\", \"gcp_unittests\": \"a7e22661dbba4659f295c149103f4ed32c5694b0\", \"gfx_unittests\": \"f5c752a9f4e1d1aac07378289fe0ac34428b2971\", \"gin_unittests\": \"21e2d5a6ceb4e27ebb80d52eb8cf958ed0985dad\", \"google_apis_unittests\": \"6ae4459ca1129f0da3444a13f8efe2dbed435b10\", \"gpu_unittests\": \"fd7af70fd51b2795a6d3e4d6ee741ecaaed1919f\", \"gwp_asan_unittests\": \"edcea38c9145d7751c7c228256b8d8d5f1563468\", \"headless_browsertests\": \"d42261a429a07409b26966b343e968f3ce20ad8c\", \"headless_unittests\": \"ad32fa94b43e97663d58695c082bcd93156b2976\", \"install_static_unittests\": \"b2d3aa4b401706013a67cf46f03f97d132049958\", \"installer_util_unittests\": \"59d2fb61704739d4b72890483cb05da74f29e387\", \"interactive_ui_tests\": \"5fb118a67c3f3f25c15782f2af5c6b83b1785acb\", \"ipc_tests\": \"6d61e56ae53dea926b2b3b562d1f36c378000108\", \"jingle_unittests\": \"b6628d5d8cfbf462974c7c040c6ecf3053c8261d\", \"latency_unittests\": \"ba7c371eeee0759bd824b60ef597eb4011bcb7c3\", \"libjingle_xmpp_unittests\": \"0da358ee6755f7e75d618bc07843ee534c3f7537\", \"liburlpattern_unittests\": \"ef65e34a58b90adace0f50ffa2ed5081ff14c9c3\", \"media_blink_unittests\": \"0b104b1f5f4c3e131904bc9292bce172be0fdcf4\", \"media_unittests\": \"fe37b0bd0d3eb87b9c3f0859089de2a582fdeb50\", \"message_center_unittests\": \"f4b7175418d3b65236fe017ef6d5dda5f89d05b4\", \"midi_unittests\": \"48d9f4a002d2ef244fb14fef422232070e974302\", \"mojo_core_unittests\": \"7ed1594d81e1e0070d7ff8058f673b6c854d1b5d\", \"mojo_unittests\": \"180a4951304d35e23bdd8345f8902060f1e29311\", \"nacl_loader_unittests\": \"b50b554008f5bc067a102cd1bfd6886129da3035\", \"native_theme_unittests\": \"9fb07fed36aef82b991492c1ae7df79ddf81d038\", \"net_unittests\": \"633843a90df520749a6996ae3f66e8ac1cd70419\", \"notification_helper_unittests\": \"1c930e20a879192b1ebb769c18188d68a7d7e5ad\", \"pdf_unittests\": \"ea80488709fbe6a11476e7766d45810bceb9d0b2\", \"perfetto_unittests\": \"6fe87fea85c37af581a91b1277dffe98d3e669cb\", \"ppapi_unittests\": \"529e67eed25ed64fc1b223b20635e96c9583b451\", \"printing_unittests\": \"6ec78ade12e52e7349ad37f3874beba9044cabf6\", \"remoting_unittests\": \"790d3a0e7365241a24665fd96f7bbe05a9c196d6\", \"sbox_integration_tests\": \"bf91f9a6bcb9e2534336bebaead84c577efb2a0a\", \"sbox_unittests\": \"63eb50860dbbea145c1cea388f79faec01e298e1\", \"sbox_validation_tests\": \"8219b226354b88ccaec9d6b2c5e9c9f503e8da20\", \"service_manager_unittests\": \"59a2349ebed57685669c763eea41eef2f15a6184\", \"services_unittests\": \"46d5c542adf91761f01dee085bdb2d7356c40e73\", \"setup_unittests\": \"d63824b063349658c61ce1c94a3f5f882e1a2405\", \"shell_dialogs_unittests\": \"d2c05e2e85ba53b92c8caa6e259371e5b6077c59\", \"skia_unittests\": \"78d09d375756473d3333fda7bb8da66fad2ff9ad\", \"snapshot_unittests\": \"8e4a209aaad7ac8f5fa4d50160e93cc14f767a86\", \"sql_unittests\": \"66f640a68fb73beca8912091a65108c899da493c\", \"storage_unittests\": \"db2712aa085cb9b4a4028996ded3b76b49ce89ea\", \"sync_integration_tests\": \"812c7953f752f6fc13e20d0edbc2e564a78ab162\", \"traffic_annotation_auditor_unittests\": \"41bb6643464afb54091fb0e20157597394b6853e\", \"ui_base_unittests\": \"7cafba1c3573c480949f513415b1174bd1a2433b\", \"ui_touch_selection_unittests\": \"95cb3d13578cccb1bcb92e6ce8516ed4dd572a66\", \"unit_tests\": \"db8118a14459cfbe6aa8236f2d39b3705bc94dd7\", \"updater_tests\": \"886b3b15bed66764508bda6e72c45c40821d67d8\", \"url_unittests\": \"d3ef985afdec57e564ed2cc36bff6550fbfa3d56\", \"views_unittests\": \"b05b92b888ef6e2701b0f8e2df4f5a0dbd76bfb3\", \"viz_unittests\": \"7b918619943206db8e1621962ae18c567388ea63\", \"vr_common_unittests\": \"4f71fca2ce484125a5b12dd030106a97188a0490\", \"vr_pixeltests\": \"7d7ea8479573dffc442f632ee3a605a3a7d5edb0\", \"weblayer_browsertests\": \"63753d3d3c2fd55a5dc6c2c4385d954ddc6877d5\", \"weblayer_unittests\": \"f8872541fe41382fc43b61471854667ddee7360d\", \"wm_unittests\": \"c2d6d89c76a9615057ecbf11604f95c2ed4f8262\", \"wtf_unittests\": \"4ac77913178c27fa556df917be9194d05400405c\", \"zlib_unittests\": \"0474bd01afd2c6094b4b9275258061bbaa200033\", \"zucchini_unittests\": \"eb387151cdddd0229e3190963e3b90f70d3e1c1c\"}}, \"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-g9w4\"], \"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\": [\"5632-2e614e1\"], \"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": "1605572218220240",
"status": "COMPLETED",
"status_changed_ts": "1605575917584112",
"tags": [
"build_address:luci.chromium.ci/win-asan/15676",
"builder:win-asan",
"buildset:commit/git/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/adad15ccc5eafa094d92ea3b5d9e0c35cef6cccc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028859288235859008",
"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/8863429226009054160/+/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:4fec614c8d6f0310",
"user_agent:luci-scheduler"
],
"updated_ts": "1605575917611689",
"url": "https://ci.chromium.org/b/8863429226009054160",
"utcnow_ts": "1605575919353430"
}
}
[01:18:39.364] Invocation finished in 1h1m46.792972151s with status SUCCEEDED