[22:31:40.390] New invocation is queued and will start shortly
[22:31:41.628] Starting the invocation (attempt 1)
[22:31:41.664] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:41.664] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:41.664] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024879640533838464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d5b5b733de1379e0298982b3da50ac81f5153e9e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024879640533838464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"gitiles_ref:refs/heads/master"
]
}
[22:31:42.609] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609367501777459",
"id": "8859449578415090000",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5b5b733de1379e0298982b3da50ac81f5153e9e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609367502330087",
"tags": [
"build_address:luci.chromium.ci/win-asan/16561",
"builder:win-asan",
"buildset:commit/git/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024879640533838464",
"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/8859449578415090000/+/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": "1609367502330201",
"url": "https://ci.chromium.org/b/8859449578415090000",
"utcnow_ts": "1609367502599424"
}
}
[22:31:42.609] Task URL: https://ci.chromium.org/b/8859449578415090000
[22:31:42.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:2:0) after 1m0s
[22:32:40.572] Received PubSub notification, asking Buildbucket for the build status
[22:32:40.651] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:2:0)
[22:32:42.656] Timer tick, asking Buildbucket for the build status
[22:32:42.728] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:4:0) after 1m0s
[22:33:42.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:4:0)
[22:33:42.837] Timer tick, asking Buildbucket for the build status
[22:33:42.937] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:42.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:5:0) after 1m0s
[22:34:43.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:5:0)
[22:34:43.053] Timer tick, asking Buildbucket for the build status
[22:34:43.223] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:6:0) after 1m0s
[22:35:43.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:6:0)
[22:35:43.254] Timer tick, asking Buildbucket for the build status
[22:35:43.350] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:43.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:7:0) after 1m0s
[22:36:43.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:7:0)
[22:36:43.352] Timer tick, asking Buildbucket for the build status
[22:36:43.462] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:43.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:8:0) after 1m0s
[22:37:43.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:8:0)
[22:37:43.530] Timer tick, asking Buildbucket for the build status
[22:37:43.653] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:43.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:9:0) after 1m0s
[22:38:43.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:9:0)
[22:38:43.737] Timer tick, asking Buildbucket for the build status
[22:38:43.811] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:43.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:10:0) after 1m0s
[22:39:43.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:10:0)
[22:39:43.853] Timer tick, asking Buildbucket for the build status
[22:39:44.065] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:44.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:11:0) after 1m0s
[22:40:44.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:11:0)
[22:40:44.137] Timer tick, asking Buildbucket for the build status
[22:40:44.204] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:44.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:12:0) after 1m0s
[22:41:44.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:12:0)
[22:41:44.238] Timer tick, asking Buildbucket for the build status
[22:41:44.730] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:44.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:13:0) after 1m0s
[22:42:44.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:13:0)
[22:42:44.823] Timer tick, asking Buildbucket for the build status
[22:42:44.885] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:44.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:14:0) after 1m0s
[22:43:44.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:14:0)
[22:43:44.868] Timer tick, asking Buildbucket for the build status
[22:43:44.934] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:44.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:15:0) after 1m0s
[22:44:44.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:15:0)
[22:44:44.929] Timer tick, asking Buildbucket for the build status
[22:44:45.033] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:45.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:16:0) after 1m0s
[22:45:45.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:16:0)
[22:45:45.079] Timer tick, asking Buildbucket for the build status
[22:45:45.176] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:45.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:17:0) after 1m0s
[22:46:45.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:17:0)
[22:46:45.277] Timer tick, asking Buildbucket for the build status
[22:46:45.426] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:45.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:18:0) after 1m0s
[22:47:45.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:18:0)
[22:47:45.544] Timer tick, asking Buildbucket for the build status
[22:47:45.724] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:45.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:19:0) after 1m0s
[22:48:45.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:19:0)
[22:48:45.748] Timer tick, asking Buildbucket for the build status
[22:48:45.859] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:45.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:20:0) after 1m0s
[22:49:45.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:20:0)
[22:49:45.930] Timer tick, asking Buildbucket for the build status
[22:49:46.213] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:46.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:21:0) after 1m0s
[22:50:46.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:21:0)
[22:50:46.340] Timer tick, asking Buildbucket for the build status
[22:50:46.489] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:46.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:22:0) after 1m0s
[22:51:46.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:22:0)
[22:51:46.617] Timer tick, asking Buildbucket for the build status
[22:51:46.718] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:46.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:23:0) after 1m0s
[22:52:46.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:23:0)
[22:52:46.742] Timer tick, asking Buildbucket for the build status
[22:52:46.869] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:46.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:24:0) after 1m0s
[22:53:47.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:24:0)
[22:53:47.044] Timer tick, asking Buildbucket for the build status
[22:53:47.187] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:47.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:25:0) after 1m0s
[22:54:47.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:25:0)
[22:54:47.239] Timer tick, asking Buildbucket for the build status
[22:54:47.622] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:47.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:26:0) after 1m0s
[22:55:47.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:26:0)
[22:55:47.644] Timer tick, asking Buildbucket for the build status
[22:55:47.755] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:47.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:27:0) after 1m0s
[22:56:47.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:27:0)
[22:56:47.789] Timer tick, asking Buildbucket for the build status
[22:56:47.918] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:47.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:28:0) after 1m0s
[22:57:47.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:28:0)
[22:57:47.922] Timer tick, asking Buildbucket for the build status
[22:57:48.030] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:48.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:29:0) after 1m0s
[22:58:48.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:29:0)
[22:58:48.102] Timer tick, asking Buildbucket for the build status
[22:58:48.566] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:48.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:30:0) after 1m0s
[22:59:48.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:30:0)
[22:59:48.661] Timer tick, asking Buildbucket for the build status
[22:59:49.836] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:49.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:31:0) after 1m0s
[23:00:50.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:31:0)
[23:00:50.003] Timer tick, asking Buildbucket for the build status
[23:00:50.172] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:50.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:32:0) after 1m0s
[23:01:50.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:32:0)
[23:01:50.449] Timer tick, asking Buildbucket for the build status
[23:01:51.140] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:51.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:33:0) after 1m0s
[23:02:51.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:33:0)
[23:02:51.224] Timer tick, asking Buildbucket for the build status
[23:02:51.370] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:51.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:34:0) after 1m0s
[23:03:51.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:34:0)
[23:03:51.436] Timer tick, asking Buildbucket for the build status
[23:03:51.566] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:51.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:35:0) after 1m0s
[23:04:51.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:35:0)
[23:04:51.649] Timer tick, asking Buildbucket for the build status
[23:04:51.718] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:36:0) after 1m0s
[23:05:51.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:36:0)
[23:05:51.855] Timer tick, asking Buildbucket for the build status
[23:05:51.942] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:51.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:37:0) after 1m0s
[23:06:51.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:37:0)
[23:06:51.947] Timer tick, asking Buildbucket for the build status
[23:06:52.079] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:52.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:38:0) after 1m0s
[23:07:52.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:38:0)
[23:07:52.212] Timer tick, asking Buildbucket for the build status
[23:07:52.527] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:52.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:39:0) after 1m0s
[23:08:52.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:39:0)
[23:08:52.666] Timer tick, asking Buildbucket for the build status
[23:08:52.766] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:40:0) after 1m0s
[23:09:52.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:40:0)
[23:09:52.811] Timer tick, asking Buildbucket for the build status
[23:09:52.905] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:41:0) after 1m0s
[23:10:52.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:41:0)
[23:10:52.990] Timer tick, asking Buildbucket for the build status
[23:10:53.057] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:53.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:42:0) after 1m0s
[23:11:53.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:42:0)
[23:11:53.129] Timer tick, asking Buildbucket for the build status
[23:11:53.277] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:53.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:43:0) after 1m0s
[23:12:53.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:43:0)
[23:12:53.276] Timer tick, asking Buildbucket for the build status
[23:12:53.798] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:53.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:44:0) after 1m0s
[23:13:53.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:44:0)
[23:13:53.814] Timer tick, asking Buildbucket for the build status
[23:13:53.940] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:53.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:45:0) after 1m0s
[23:14:53.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:45:0)
[23:14:53.992] Timer tick, asking Buildbucket for the build status
[23:14:54.096] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:54.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:46:0) after 1m0s
[23:15:54.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:46:0)
[23:15:54.130] Timer tick, asking Buildbucket for the build status
[23:15:54.382] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:54.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:47:0) after 1m0s
[23:16:54.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:47:0)
[23:16:54.399] Timer tick, asking Buildbucket for the build status
[23:16:54.516] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:54.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:48:0) after 1m0s
[23:17:54.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:48:0)
[23:17:54.570] Timer tick, asking Buildbucket for the build status
[23:17:54.998] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:49:0) after 1m0s
[23:18:55.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:49:0)
[23:18:55.068] Timer tick, asking Buildbucket for the build status
[23:18:55.178] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:55.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:50:0) after 1m0s
[23:19:55.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:50:0)
[23:19:55.251] Timer tick, asking Buildbucket for the build status
[23:19:55.394] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:55.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:51:0) after 1m0s
[23:20:55.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:51:0)
[23:20:55.414] Timer tick, asking Buildbucket for the build status
[23:20:55.485] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:55.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:52:0) after 1m0s
[23:21:55.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:52:0)
[23:21:55.506] Timer tick, asking Buildbucket for the build status
[23:21:55.623] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:55.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:53:0) after 1m0s
[23:22:55.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:53:0)
[23:22:55.683] Timer tick, asking Buildbucket for the build status
[23:22:55.751] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:54:0) after 1m0s
[23:23:55.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:54:0)
[23:23:55.775] Timer tick, asking Buildbucket for the build status
[23:23:56.793] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:56.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:55:0) after 1m0s
[23:24:56.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:55:0)
[23:24:56.812] Timer tick, asking Buildbucket for the build status
[23:24:57.420] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:57.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:56:0) after 1m0s
[23:25:57.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:56:0)
[23:25:57.460] Timer tick, asking Buildbucket for the build status
[23:25:57.578] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:57.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:57:0) after 1m0s
[23:26:57.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:57:0)
[23:26:57.550] Timer tick, asking Buildbucket for the build status
[23:26:57.661] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:57.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:58:0) after 1m0s
[23:27:57.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:58:0)
[23:27:57.716] Timer tick, asking Buildbucket for the build status
[23:27:57.795] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:57.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:59:0) after 1m0s
[23:28:57.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:59:0)
[23:28:57.874] Timer tick, asking Buildbucket for the build status
[23:28:58.182] Build 8859449578415090000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:58.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024879640533838464:60:0) after 1m0s
[23:29:43.062] Received PubSub notification, asking Buildbucket for the build status
[23:29:43.126] Build 8859449578415090000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:29:43.126] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609370981353240",
"created_by": "project:chromium",
"created_ts": "1609367501777459",
"id": "8859449578415090000",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5b5b733de1379e0298982b3da50ac81f5153e9e\"}}",
"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\": \"d5b5b733de1379e0298982b3da50ac81f5153e9e\", \"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\": 16561, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"938399865e720c2e37616cdad64109ef331bb035\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"d5b5b733de1379e0298982b3da50ac81f5153e9e\", \"got_revision_cp\": \"refs/heads/master@{#839807}\", \"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\": \"d5b5b733de1379e0298982b3da50ac81f5153e9e\", \"swarm_hashes_refs/heads/master(at){#839807}_without_patch\": {\"absl_hardening_tests\": \"3ca82dbaabba65bac2cba0d03cfaec3dc2c31202\", \"accessibility_unittests\": \"6a4a035aa6c0bd7cded342ea67c4eedd5b376c9d\", \"angle_unittests\": \"39633b6a117ed071d66aed213cb361fc84fbd497\", \"app_shell_unittests\": \"af532f180030865c8b6c3a6b943224cde2ffed66\", \"aura_unittests\": \"de80d334411c28063f3c0272bfa7d74b3705d3a4\", \"base_unittests\": \"937d73d48e1882e8fabb28cbe73badd20fdc45c6\", \"base_util_unittests\": \"1cdc7016aa67f7db15faf4afc6cd35c76f2d87e3\", \"blink_common_unittests\": \"7c2303cea2fd50dd2c32d790f4989ac1a9a26e4d\", \"blink_fuzzer_unittests\": \"2c7a80a70c98ed9c8e2660b273995bab28d4299e\", \"blink_heap_unittests\": \"96be0639c97d1312c853a37932de34d98a0d3ea0\", \"blink_platform_unittests\": \"ba098b5be5dbffc7ca1a8bb2b62c1e0bb11d639d\", \"blink_unittests\": \"8933bf91dd7ae4c8fce6fe56c7866b6fd88b38b4\", \"boringssl_crypto_tests\": \"7a30a92e8c1600f750e0f0c953f76839ce17c5b6\", \"boringssl_ssl_tests\": \"f39aa66eba77f3141a5a1aae04323814c055ba8f\", \"browser_switcher_bho_unittests\": \"65e5528c655665a92c69e44475d7b8df5be95ccd\", \"browser_tests\": \"7d681a19d6e905ff16823a854f011ad8fc40896b\", \"capture_unittests\": \"6d95eb0e31db20fb785bbd62c785882165bfd3b1\", \"cast_unittests\": \"10d0b8f343d482a0785e1b07f800a8637a1879aa\", \"cc_unittests\": \"af71532e4054a0a2c32d349cc7f21e313abb0edb\", \"chrome_app_unittests\": \"1b88e13495aaad6392ff042f6ec2da29fae2c221\", \"chrome_cleaner_unittests\": \"810364de716f49e37f39a532ea2d5b06cecf4c82\", \"chrome_elf_unittests\": \"4460d7f75294385de1a3bcb1ecf261c7e51ec639\", \"chromedriver_unittests\": \"1d068c2d221c568b57d0de64660404fd14907672\", \"components_browsertests\": \"9a455deb23c86e8acbef0e0089ca3c02950abab1\", \"components_unittests\": \"4e546a4d5f86bd9e735058d19a301a11a60556f5\", \"compositor_unittests\": \"c6ab2d653c7707ca14188f44878270739e5f3f52\", \"content_browsertests\": \"6a28429257d84cfb64c8d93d203a3c2080b76400\", \"content_unittests\": \"425bf8eb5d7e0154c0f36f1d2ec2d824dd277279\", \"courgette_unittests\": \"1b51ca14e8a1ce0a89e692766794f1d2b7e11dd5\", \"crashpad_tests\": \"bd8dbd1ed9c418781e470d952c975ebbaa95bcef\", \"cronet_tests\": \"7e10d3d1da3d212c774ebfe29d78f1ba93e3da15\", \"cronet_unittests\": \"2281d4abb4dc5aee20492423fb3b7df3a9fbd6e2\", \"crypto_unittests\": \"bfd899cacdc1ba9e35e38a0844284d3dac46f755\", \"delayloads_unittests\": \"44cc6e948bf8fc22dd7df05b8fb4b4199f8de7ab\", \"device_unittests\": \"8a921c06877ad8b29589d055e0130393a0a16785\", \"display_unittests\": \"a1f9c4160045f8fbdf07bf388358d0dd3cb46a67\", \"elevation_service_unittests\": \"90a9a0bb5fbcb805013532bb4ae8b0d83bf42cb7\", \"events_unittests\": \"8e250cfeadb0ad1ebc881bbc6ba4a1c58f4fe854\", \"extensions_browsertests\": \"61c49d87f60dc37a2571cae20e1d7c10aab1b0fd\", \"extensions_unittests\": \"4a042a4fb63c762e8d45fd7e0a7b35128a030b7c\", \"filesystem_service_unittests\": \"04193e33bf434a5c9b864fb3f270b516db11427f\", \"gcm_unit_tests\": \"91c063b0cb72ff3e4cecfb2295d0d97015115b9b\", \"gcp_unittests\": \"1982e7cc736f8847dde50cd6c9322ce8e64985f4\", \"gfx_unittests\": \"8e60d7638a025acf1682d2cf12b64f7e6b839f6a\", \"gin_unittests\": \"c6b4ea0a8d86759baa22b2e50afe45dfb3352260\", \"google_apis_unittests\": \"1ce3fd01739c0f6846e41b91941693eff127738e\", \"gpu_unittests\": \"4e455ec8d5c16c1d5d08f0f83ec7ce229902ea18\", \"gwp_asan_unittests\": \"0bf41fb37aa03105eb69a6661850bdb84796ad61\", \"headless_browsertests\": \"824c551ee36a43a193acb817c280e8988cbe79fa\", \"headless_unittests\": \"55d29a6a04e5a4d53da704eb4e9f28aa6b73be7b\", \"install_static_unittests\": \"37c9c1e050cebe0124eb8b80d122d3622e5903f3\", \"installer_util_unittests\": \"23aae5bcc6c421ba42c0fe43d30c71b37905bbbb\", \"interactive_ui_tests\": \"3baff5276dc9eef48880eda8ea2f7d9f0d5b48fb\", \"ipc_tests\": \"703e7aad2a1d1d7069bc9a981c770575e887ac5b\", \"jingle_unittests\": \"e25fb4169b0b5841195eb5e1a525389cd7766215\", \"latency_unittests\": \"64cb76fe7bf1cfec9678e5b87bfbaaacbf55dbd7\", \"libjingle_xmpp_unittests\": \"5df25f941cdf39d69d6028544d4588653fe61515\", \"liburlpattern_unittests\": \"9ef69c8d10127418c5d86c59cc4bfb0079114634\", \"media_blink_unittests\": \"c501019246aa9834cc893c73ee444c870ffba978\", \"media_unittests\": \"ebaf5e0238bc4cef2bad63226b6f8b5e035a75df\", \"message_center_unittests\": \"5149f01c73255777b002f2019630ceed25619854\", \"midi_unittests\": \"1f23186c5023e9318867a5b2c305a85cf10f6a29\", \"mojo_core_unittests\": \"2cdceb1d9035231fa21918ec38a12b347605a8e9\", \"mojo_unittests\": \"589b20f3c5ac0b508caee72621dee0dda4c4375f\", \"nacl_loader_unittests\": \"dd087349bd5b60fa659898b44d52c6762031b76f\", \"native_theme_unittests\": \"5ea80d28d3f88a824abe3d4caba804b4a3e6ce60\", \"net_unittests\": \"b09c94ee504e556cb8203267d20b3ee85dad0fd6\", \"notification_helper_unittests\": \"98eea513a840f640ec6cde039726fae504573c7b\", \"pdf_unittests\": \"923d26a0d2c50147577c6bb3cb10f84b8b95c2b4\", \"perfetto_unittests\": \"0cdda5fb1714d9c963bb7b03b4fc73cf3a2e0123\", \"ppapi_unittests\": \"ac020c69bcef8039baf8e8d7e2fe1c934e8e3cc7\", \"printing_unittests\": \"9eb661078b60d418165579e82c62956a77137abb\", \"remoting_unittests\": \"ea5ab3421d3d14fbccb97fef24a085e082e395f6\", \"sbox_integration_tests\": \"b4cc33443f0ef416f54fdbf72215bf8f2c2511c8\", \"sbox_unittests\": \"2327f7e72bc2dcd8cde8f208da45308d13dd803c\", \"sbox_validation_tests\": \"2d9bf615dc9df4dc6916bb58abc3a06c0e0e34a3\", \"service_manager_unittests\": \"5c7cc8cb11b1b239b7c32040ec874d40fdc9a6fe\", \"services_unittests\": \"837da1d4f3cf570e5632efd319a5b0442142dd4e\", \"setup_unittests\": \"733ef137bbcbe1f24065a6ae00ef64c7ab672cea\", \"shell_dialogs_unittests\": \"26b301bab3238d3a51ca3365f3975d79d0d41778\", \"skia_unittests\": \"de32844b805f803e6c1f7457584601bb4680dfee\", \"snapshot_unittests\": \"bb846e369dfa6765d414fbbad455c67128a81541\", \"sql_unittests\": \"02cfd58ed75e20ef8385687ef0bce4d021d77a31\", \"storage_unittests\": \"059deb644149c038828ee6d41f675a23e60dbda5\", \"sync_integration_tests\": \"52b034f1c695f758f5b5ca3e6573a5b52ab97a73\", \"traffic_annotation_auditor_unittests\": \"711db2a359dc2a4b1f6f071ac7cc815bf112ccf4\", \"ui_base_unittests\": \"01f49e253a02091ab5ff23bfa76c7162783ee6aa\", \"ui_touch_selection_unittests\": \"142659ff90b2bd45e15e93764c318da18be4a5b6\", \"unit_tests\": \"7d680c725ad25766e2769d0fcd234278ab8eef89\", \"updater_tests\": \"61955692b1d938c0f3a197916c59fa70c3043754\", \"url_unittests\": \"b9f9501c1ddbc70b8e8de58943143fe0cd47f116\", \"views_unittests\": \"6cd296dfcfc2431c90d49486ad0ef60577ed1cc4\", \"viz_unittests\": \"08fa5788931fa21e24899a7b05eab65f3bcbfef4\", \"vr_common_unittests\": \"034cb0d9e8a9f74dc142ad0a3dc904358776c68b\", \"vr_pixeltests\": \"264e1d08b1fff4ff0c7355c8ae5774868c54d7e1\", \"weblayer_browsertests\": \"c92181eab220d65d4990351e2da71881375998dd\", \"weblayer_unittests\": \"bc278b8c1eaa028237622cabef409d91e6d1606e\", \"wm_unittests\": \"cb50a50ed459dca8706b97d5b93c7dc2d5b1668e\", \"wtf_unittests\": \"41bbde7cadf473dc2cfd1f647b070b8391bfa1c5\", \"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": "1609367551613849",
"status": "COMPLETED",
"status_changed_ts": "1609370981684650",
"tags": [
"build_address:luci.chromium.ci/win-asan/16561",
"builder:win-asan",
"buildset:commit/git/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5b5b733de1379e0298982b3da50ac81f5153e9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024879640533838464",
"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/8859449578415090000/+/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:50ce98c7ffcde710",
"user_agent:luci-scheduler"
],
"updated_ts": "1609370981688716",
"url": "https://ci.chromium.org/b/8859449578415090000",
"utcnow_ts": "1609370983115832"
}
}
[23:29:43.126] Invocation finished in 58m2.752663441s with status SUCCEEDED